builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-016 starttime: 1482767341.52 results: success (0) buildid: 20161226064736 builduid: 5cbeebde563446c784c3a4c58c2aa6d3 revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.516609) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.517164) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.517448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.533343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.533828) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 07:49:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.6M=0s 2016-12-26 07:49:01 (42.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112402 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.660548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.660880) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018939 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.710619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.711013) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 07:49:01,915 truncating revision to first 12 chars 2016-12-26 07:49:01,915 Setting DEBUG logging. 2016-12-26 07:49:01,915 attempt 1/10 2016-12-26 07:49:01,915 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1747e26ee37b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 07:49:02,246 unpacking tar archive at: mozilla-central-1747e26ee37b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.708059 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:02.433708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:02.434341) ========= script_repo_revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:02.435045) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:02.435468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:02.447163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-26 07:49:02.447727) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:49:02 INFO - MultiFileLogger online at 20161226 07:49:02 in /builds/slave/test 07:49:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:49:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:02 INFO - {'append_to_log': False, 07:49:02 INFO - 'base_work_dir': '/builds/slave/test', 07:49:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:49:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:02 INFO - 'branch': 'Firefox-Non-PGO', 07:49:02 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:02 INFO - 'config_files': ('talos/linux_config.py',), 07:49:02 INFO - 'default_actions': ('clobber', 07:49:02 INFO - 'read-buildbot-config', 07:49:02 INFO - 'download-and-extract', 07:49:02 INFO - 'populate-webroot', 07:49:02 INFO - 'create-virtualenv', 07:49:02 INFO - 'install', 07:49:02 INFO - 'run-tests'), 07:49:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:02 INFO - 'download_minidump_stackwalk': True, 07:49:02 INFO - 'download_symbols': 'ondemand', 07:49:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:02 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:02 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:02 INFO - 'installer_path': 'installer.exe', 07:49:02 INFO - 'log_level': 'info', 07:49:02 INFO - 'log_name': 'talos', 07:49:02 INFO - 'log_to_console': True, 07:49:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:49:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:49:02 INFO - 'opt_config_files': (), 07:49:02 INFO - 'pip_index': False, 07:49:02 INFO - 'sps_profile': False, 07:49:02 INFO - 'sps_profile_interval': 0, 07:49:02 INFO - 'suite': 'dromaeojs-e10s', 07:49:02 INFO - 'system_bits': '32', 07:49:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:02 INFO - 'title': 'talos-linux64-ix-016', 07:49:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:02 INFO - 'use_talos_json': True, 07:49:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:02 INFO - 'work_dir': 'build'} 07:49:02 INFO - [mozharness: 2016-12-26 15:49:02.756122Z] Running clobber step. 07:49:02 INFO - Running pre-action listener: _resource_record_pre_action 07:49:02 INFO - Running main action method: clobber 07:49:02 INFO - rmtree: /builds/slave/test/build 07:49:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:03 INFO - Running post-action listener: _resource_record_post_action 07:49:03 INFO - [mozharness: 2016-12-26 15:49:03.717041Z] Finished clobber step (success) 07:49:03 INFO - [mozharness: 2016-12-26 15:49:03.717139Z] Running read-buildbot-config step. 07:49:03 INFO - Running pre-action listener: _resource_record_pre_action 07:49:03 INFO - Running main action method: read_buildbot_config 07:49:03 INFO - Using buildbot properties: 07:49:03 INFO - { 07:49:03 INFO - "project": "", 07:49:03 INFO - "product": "firefox", 07:49:03 INFO - "script_repo_revision": "production", 07:49:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 07:49:03 INFO - "repository": "", 07:49:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 07:49:03 INFO - "buildid": "20161226064736", 07:49:03 INFO - "pgo_build": "False", 07:49:03 INFO - "basedir": "/builds/slave/test", 07:49:03 INFO - "buildnumber": 588, 07:49:03 INFO - "slavename": "talos-linux64-ix-016", 07:49:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:49:03 INFO - "platform": "ubuntu64_hw", 07:49:03 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:49:03 INFO - "branch": "mozilla-central", 07:49:03 INFO - "repo_path": "mozilla-central", 07:49:03 INFO - "revision": "1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9", 07:49:03 INFO - "stage_platform": "linux64", 07:49:03 INFO - "builduid": "5cbeebde563446c784c3a4c58c2aa6d3", 07:49:03 INFO - "slavebuilddir": "test" 07:49:03 INFO - } 07:49:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:49:03 INFO - Running post-action listener: _resource_record_post_action 07:49:03 INFO - [mozharness: 2016-12-26 15:49:03.719554Z] Finished read-buildbot-config step (success) 07:49:03 INFO - [mozharness: 2016-12-26 15:49:03.719644Z] Running download-and-extract step. 07:49:03 INFO - Running pre-action listener: _resource_record_pre_action 07:49:03 INFO - Running main action method: download_and_extract 07:49:03 INFO - mkdir: /builds/slave/test/build/tests 07:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:03 INFO - trying https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:49:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:49:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:49:04 INFO - Downloaded 1361 bytes. 07:49:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:49:04 INFO - Using the following test package requirements: 07:49:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:49:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:49:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'jsshell-linux-x86_64.zip'], 07:49:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:49:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:49:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:49:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:49:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:49:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:49:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:49:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:49:05 INFO - Expected file size: 20241340 07:49:05 INFO - Obtained file size: 20241340 07:49:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:49:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:49:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:49:09 INFO - Expected file size: 20241340 07:49:09 INFO - Obtained file size: 20241340 07:49:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:49:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:49:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:49:15 INFO - Expected file size: 14409776 07:49:15 INFO - Obtained file size: 14409776 07:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:15 INFO - trying https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:49:16 INFO - Downloaded 55897654 bytes. 07:49:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:16 INFO - mkdir: /builds/slave/test/properties 07:49:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:49:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:49:16 INFO - Contents: 07:49:16 INFO - build_url:https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:17 INFO - Running post-action listener: _resource_record_post_action 07:49:17 INFO - Running post-action listener: set_extra_try_arguments 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.024329Z] Finished download-and-extract step (success) 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.024558Z] Running populate-webroot step. 07:49:17 INFO - Running pre-action listener: _resource_record_pre_action 07:49:17 INFO - Running main action method: populate_webroot 07:49:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:49:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:49:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:49:17 INFO - '--tppagecycles', 07:49:17 INFO - '7'], 07:49:17 INFO - 'tests': ['tsvgm']}}, 07:49:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:49:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:49:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:49:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:49:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['damp', 'tps']}, 07:49:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'tests': ['damp', 'tps']}, 07:49:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['dromaeo_dom']}, 07:49:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:49:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['basic_compositor_video']}, 07:49:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:49:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tsvgx', 07:49:17 INFO - 'tsvgr_opacity', 07:49:17 INFO - 'tart', 07:49:17 INFO - 'tscrollx', 07:49:17 INFO - 'cart', 07:49:17 INFO - 'tsvg_static']}, 07:49:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:49:17 INFO - 'tsvgr_opacity', 07:49:17 INFO - 'tart', 07:49:17 INFO - 'tscrollx', 07:49:17 INFO - 'cart', 07:49:17 INFO - 'tsvg_static']}, 07:49:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tp5o']}, 07:49:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:49:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s', 07:49:17 INFO - '--xperf_path', 07:49:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:17 INFO - 'tests': ['tp5n']}, 07:49:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--xperf_path', 07:49:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:17 INFO - 'tests': ['tp5n']}}, 07:49:17 INFO - 'talos.zip': {'path': '', 07:49:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:49:17 INFO - Running post-action listener: _resource_record_post_action 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.044854Z] Finished populate-webroot step (success) 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.044944Z] Running create-virtualenv step. 07:49:17 INFO - Running pre-action listener: _resource_record_pre_action 07:49:17 INFO - Running main action method: create_virtualenv 07:49:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:49:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:49:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:49:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:49:17 INFO - Using real prefix '/usr' 07:49:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:49:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:49:19 INFO - Installing pip.................done. 07:49:19 INFO - Return code: 0 07:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:49:19 INFO - Reading from file tmpfile_stdout 07:49:19 INFO - Output received: 07:49:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:49:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:49:19 INFO - Using env: {'DISPLAY': ':0', 07:49:19 INFO - 'HOME': '/home/cltbld', 07:49:19 INFO - 'LANG': 'en_US.UTF-8', 07:49:19 INFO - 'LANGUAGE': 'en_US:en', 07:49:19 INFO - 'LOGNAME': 'cltbld', 07:49:19 INFO - 'MAIL': '/var/mail/cltbld', 07:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:19 INFO - 'MOZ_NO_REMOTE': '1', 07:49:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:19 INFO - 'NO_EM_RESTART': '1', 07:49:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:19 INFO - 'PWD': '/builds/slave/test', 07:49:19 INFO - 'SHELL': '/bin/bash', 07:49:19 INFO - 'SHLVL': '1', 07:49:19 INFO - 'TERM': 'linux', 07:49:19 INFO - 'TMOUT': '86400', 07:49:19 INFO - 'USER': 'cltbld', 07:49:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:19 INFO - '_': '/tools/buildbot/bin/python'} 07:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:49:20 INFO - Cleaning up... 07:49:20 INFO - Return code: 0 07:49:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:49:20 INFO - Using env: {'DISPLAY': ':0', 07:49:20 INFO - 'HOME': '/home/cltbld', 07:49:20 INFO - 'LANG': 'en_US.UTF-8', 07:49:20 INFO - 'LANGUAGE': 'en_US:en', 07:49:20 INFO - 'LOGNAME': 'cltbld', 07:49:20 INFO - 'MAIL': '/var/mail/cltbld', 07:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:20 INFO - 'MOZ_NO_REMOTE': '1', 07:49:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:20 INFO - 'NO_EM_RESTART': '1', 07:49:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:20 INFO - 'PWD': '/builds/slave/test', 07:49:20 INFO - 'SHELL': '/bin/bash', 07:49:20 INFO - 'SHLVL': '1', 07:49:20 INFO - 'TERM': 'linux', 07:49:20 INFO - 'TMOUT': '86400', 07:49:20 INFO - 'USER': 'cltbld', 07:49:20 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:20 INFO - '_': '/tools/buildbot/bin/python'} 07:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:20 INFO - Downloading/unpacking psutil>=3.1.1 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:49:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:22 INFO - Installing collected packages: psutil 07:49:22 INFO - Running setup.py install for psutil 07:49:22 INFO - building 'psutil._psutil_linux' extension 07:49:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:49:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:49:22 INFO - building 'psutil._psutil_posix' extension 07:49:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:49:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:49:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:22 INFO - Successfully installed psutil 07:49:22 INFO - Cleaning up... 07:49:22 INFO - Return code: 0 07:49:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:49:22 INFO - Using env: {'DISPLAY': ':0', 07:49:22 INFO - 'HOME': '/home/cltbld', 07:49:22 INFO - 'LANG': 'en_US.UTF-8', 07:49:22 INFO - 'LANGUAGE': 'en_US:en', 07:49:22 INFO - 'LOGNAME': 'cltbld', 07:49:22 INFO - 'MAIL': '/var/mail/cltbld', 07:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:22 INFO - 'MOZ_NO_REMOTE': '1', 07:49:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:22 INFO - 'NO_EM_RESTART': '1', 07:49:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:22 INFO - 'PWD': '/builds/slave/test', 07:49:22 INFO - 'SHELL': '/bin/bash', 07:49:22 INFO - 'SHLVL': '1', 07:49:22 INFO - 'TERM': 'linux', 07:49:22 INFO - 'TMOUT': '86400', 07:49:22 INFO - 'USER': 'cltbld', 07:49:22 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:22 INFO - '_': '/tools/buildbot/bin/python'} 07:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:49:25 INFO - Installing collected packages: mozsystemmonitor 07:49:25 INFO - Running setup.py install for mozsystemmonitor 07:49:25 INFO - Successfully installed mozsystemmonitor 07:49:25 INFO - Cleaning up... 07:49:25 INFO - Return code: 0 07:49:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:49:25 INFO - Using env: {'DISPLAY': ':0', 07:49:25 INFO - 'HOME': '/home/cltbld', 07:49:25 INFO - 'LANG': 'en_US.UTF-8', 07:49:25 INFO - 'LANGUAGE': 'en_US:en', 07:49:25 INFO - 'LOGNAME': 'cltbld', 07:49:25 INFO - 'MAIL': '/var/mail/cltbld', 07:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:25 INFO - 'MOZ_NO_REMOTE': '1', 07:49:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:25 INFO - 'NO_EM_RESTART': '1', 07:49:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:25 INFO - 'PWD': '/builds/slave/test', 07:49:25 INFO - 'SHELL': '/bin/bash', 07:49:25 INFO - 'SHLVL': '1', 07:49:25 INFO - 'TERM': 'linux', 07:49:25 INFO - 'TMOUT': '86400', 07:49:25 INFO - 'USER': 'cltbld', 07:49:25 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:25 INFO - '_': '/tools/buildbot/bin/python'} 07:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:25 INFO - Downloading/unpacking jsonschema==2.5.1 07:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:49:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:27 INFO - Downloading functools32-3.2.3-2.tar.gz 07:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:49:27 INFO - warning: no files found matching '*.txt' 07:49:27 INFO - no previously-included directories found matching 'build' 07:49:27 INFO - no previously-included directories found matching 'dist' 07:49:27 INFO - no previously-included directories found matching '.git*' 07:49:27 INFO - Installing collected packages: jsonschema, functools32 07:49:27 INFO - Running setup.py install for functools32 07:49:28 INFO - warning: no files found matching '*.txt' 07:49:28 INFO - no previously-included directories found matching 'build' 07:49:28 INFO - no previously-included directories found matching 'dist' 07:49:28 INFO - no previously-included directories found matching '.git*' 07:49:28 INFO - Successfully installed jsonschema functools32 07:49:28 INFO - Cleaning up... 07:49:28 INFO - Return code: 0 07:49:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:49:28 INFO - Using env: {'DISPLAY': ':0', 07:49:28 INFO - 'HOME': '/home/cltbld', 07:49:28 INFO - 'LANG': 'en_US.UTF-8', 07:49:28 INFO - 'LANGUAGE': 'en_US:en', 07:49:28 INFO - 'LOGNAME': 'cltbld', 07:49:28 INFO - 'MAIL': '/var/mail/cltbld', 07:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:28 INFO - 'MOZ_NO_REMOTE': '1', 07:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:28 INFO - 'NO_EM_RESTART': '1', 07:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:28 INFO - 'PWD': '/builds/slave/test', 07:49:28 INFO - 'SHELL': '/bin/bash', 07:49:28 INFO - 'SHLVL': '1', 07:49:28 INFO - 'TERM': 'linux', 07:49:28 INFO - 'TMOUT': '86400', 07:49:28 INFO - 'USER': 'cltbld', 07:49:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:28 INFO - '_': '/tools/buildbot/bin/python'} 07:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:49:28 INFO - Cleaning up... 07:49:28 INFO - Return code: 0 07:49:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:49:28 INFO - Using env: {'DISPLAY': ':0', 07:49:28 INFO - 'HOME': '/home/cltbld', 07:49:28 INFO - 'LANG': 'en_US.UTF-8', 07:49:28 INFO - 'LANGUAGE': 'en_US:en', 07:49:28 INFO - 'LOGNAME': 'cltbld', 07:49:28 INFO - 'MAIL': '/var/mail/cltbld', 07:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:28 INFO - 'MOZ_NO_REMOTE': '1', 07:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:28 INFO - 'NO_EM_RESTART': '1', 07:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:28 INFO - 'PWD': '/builds/slave/test', 07:49:28 INFO - 'SHELL': '/bin/bash', 07:49:28 INFO - 'SHLVL': '1', 07:49:28 INFO - 'TERM': 'linux', 07:49:28 INFO - 'TMOUT': '86400', 07:49:28 INFO - 'USER': 'cltbld', 07:49:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:28 INFO - '_': '/tools/buildbot/bin/python'} 07:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:28 INFO - Downloading/unpacking blobuploader==1.2.4 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:30 INFO - Downloading blobuploader-1.2.4.tar.gz 07:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:49:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:49:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:31 INFO - Downloading docopt-0.6.1.tar.gz 07:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:49:31 INFO - Installing collected packages: blobuploader, requests, docopt 07:49:31 INFO - Running setup.py install for blobuploader 07:49:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:49:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:49:31 INFO - Running setup.py install for requests 07:49:31 INFO - Running setup.py install for docopt 07:49:31 INFO - Successfully installed blobuploader requests docopt 07:49:31 INFO - Cleaning up... 07:49:31 INFO - Return code: 0 07:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:49:31 INFO - Using env: {'DISPLAY': ':0', 07:49:31 INFO - 'HOME': '/home/cltbld', 07:49:31 INFO - 'LANG': 'en_US.UTF-8', 07:49:31 INFO - 'LANGUAGE': 'en_US:en', 07:49:31 INFO - 'LOGNAME': 'cltbld', 07:49:31 INFO - 'MAIL': '/var/mail/cltbld', 07:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:31 INFO - 'MOZ_NO_REMOTE': '1', 07:49:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:31 INFO - 'NO_EM_RESTART': '1', 07:49:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:31 INFO - 'PWD': '/builds/slave/test', 07:49:31 INFO - 'SHELL': '/bin/bash', 07:49:31 INFO - 'SHLVL': '1', 07:49:31 INFO - 'TERM': 'linux', 07:49:31 INFO - 'TMOUT': '86400', 07:49:31 INFO - 'USER': 'cltbld', 07:49:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:31 INFO - '_': '/tools/buildbot/bin/python'} 07:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:32 INFO - Running setup.py (path:/tmp/pip-wE6jVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:32 INFO - Running setup.py (path:/tmp/pip-Rvw7xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:32 INFO - Running setup.py (path:/tmp/pip-kp8gvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:32 INFO - Running setup.py (path:/tmp/pip-wiRbvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:32 INFO - Running setup.py (path:/tmp/pip-lgHOW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:32 INFO - Running setup.py (path:/tmp/pip-PYP1DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:32 INFO - Running setup.py (path:/tmp/pip-1pg9Nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:32 INFO - Running setup.py (path:/tmp/pip-p0dFYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:32 INFO - Running setup.py (path:/tmp/pip-ccptX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:32 INFO - Running setup.py (path:/tmp/pip-MSNE3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:32 INFO - Running setup.py (path:/tmp/pip-wRj7UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:32 INFO - Running setup.py (path:/tmp/pip-_tiGaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:33 INFO - Running setup.py (path:/tmp/pip-dJJQH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:33 INFO - Running setup.py (path:/tmp/pip-ygh8dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:33 INFO - Running setup.py (path:/tmp/pip-OPggjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:33 INFO - Running setup.py (path:/tmp/pip-zAKh0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:33 INFO - Running setup.py (path:/tmp/pip-ZjAzd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:49:33 INFO - Running setup.py install for manifestparser 07:49:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:49:33 INFO - Running setup.py install for mozcrash 07:49:33 INFO - Running setup.py install for mozdebug 07:49:33 INFO - Running setup.py install for mozdevice 07:49:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:49:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:49:33 INFO - Running setup.py install for mozfile 07:49:33 INFO - Running setup.py install for mozhttpd 07:49:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozinfo 07:49:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozInstall 07:49:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozleak 07:49:34 INFO - Running setup.py install for mozlog 07:49:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for moznetwork 07:49:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozprocess 07:49:34 INFO - Running setup.py install for mozprofile 07:49:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozrunner 07:49:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozscreenshot 07:49:34 INFO - Running setup.py install for moztest 07:49:35 INFO - Running setup.py install for mozversion 07:49:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:49:35 INFO - Cleaning up... 07:49:35 INFO - Return code: 0 07:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:49:35 INFO - Using env: {'DISPLAY': ':0', 07:49:35 INFO - 'HOME': '/home/cltbld', 07:49:35 INFO - 'LANG': 'en_US.UTF-8', 07:49:35 INFO - 'LANGUAGE': 'en_US:en', 07:49:35 INFO - 'LOGNAME': 'cltbld', 07:49:35 INFO - 'MAIL': '/var/mail/cltbld', 07:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:35 INFO - 'MOZ_NO_REMOTE': '1', 07:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:35 INFO - 'NO_EM_RESTART': '1', 07:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:35 INFO - 'PWD': '/builds/slave/test', 07:49:35 INFO - 'SHELL': '/bin/bash', 07:49:35 INFO - 'SHLVL': '1', 07:49:35 INFO - 'TERM': 'linux', 07:49:35 INFO - 'TMOUT': '86400', 07:49:35 INFO - 'USER': 'cltbld', 07:49:35 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:35 INFO - '_': '/tools/buildbot/bin/python'} 07:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:35 INFO - Running setup.py (path:/tmp/pip-wE8SKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:35 INFO - Running setup.py (path:/tmp/pip-ihmFGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:35 INFO - Running setup.py (path:/tmp/pip-E837zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:35 INFO - Running setup.py (path:/tmp/pip-yapvE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:35 INFO - Running setup.py (path:/tmp/pip-GtuCgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:35 INFO - Running setup.py (path:/tmp/pip-v5QCbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:35 INFO - Running setup.py (path:/tmp/pip-yQbaFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:35 INFO - Running setup.py (path:/tmp/pip-Vx3ih7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:35 INFO - Running setup.py (path:/tmp/pip-kmDGoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:36 INFO - Running setup.py (path:/tmp/pip-Z_rEkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:36 INFO - Running setup.py (path:/tmp/pip-a0BeW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:36 INFO - Running setup.py (path:/tmp/pip-pcgfGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:36 INFO - Running setup.py (path:/tmp/pip-2HHY9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:36 INFO - Running setup.py (path:/tmp/pip-742XIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:36 INFO - Running setup.py (path:/tmp/pip-ibsOMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:36 INFO - Running setup.py (path:/tmp/pip-Kq7ZiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:36 INFO - Running setup.py (path:/tmp/pip-Bk9v97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:49:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:38 INFO - Downloading blessings-1.6.tar.gz 07:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:49:38 INFO - Installing collected packages: blessings 07:49:38 INFO - Running setup.py install for blessings 07:49:38 INFO - Successfully installed blessings 07:49:38 INFO - Cleaning up... 07:49:38 INFO - Return code: 0 07:49:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:39 INFO - Reading from file tmpfile_stdout 07:49:39 INFO - Current package versions: 07:49:39 INFO - argparse == 1.2.1 07:49:39 INFO - blessings == 1.6 07:49:39 INFO - blobuploader == 1.2.4 07:49:39 INFO - docopt == 0.6.1 07:49:39 INFO - functools32 == 3.2.3-2 07:49:39 INFO - jsonschema == 2.5.1 07:49:39 INFO - manifestparser == 1.1 07:49:39 INFO - mozInstall == 1.12 07:49:39 INFO - mozcrash == 1.0 07:49:39 INFO - mozdebug == 0.1 07:49:39 INFO - mozdevice == 0.49 07:49:39 INFO - mozfile == 1.2 07:49:39 INFO - mozhttpd == 0.7 07:49:39 INFO - mozinfo == 0.9 07:49:39 INFO - mozleak == 0.1 07:49:39 INFO - mozlog == 3.4 07:49:39 INFO - moznetwork == 0.27 07:49:39 INFO - mozprocess == 0.23 07:49:39 INFO - mozprofile == 0.28 07:49:39 INFO - mozrunner == 6.13 07:49:39 INFO - mozscreenshot == 0.1 07:49:39 INFO - mozsystemmonitor == 0.3 07:49:39 INFO - moztest == 0.7 07:49:39 INFO - mozversion == 1.4 07:49:39 INFO - psutil == 3.1.1 07:49:39 INFO - requests == 1.2.3 07:49:39 INFO - wsgiref == 0.1.2 07:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:49:39 INFO - Using env: {'DISPLAY': ':0', 07:49:39 INFO - 'HOME': '/home/cltbld', 07:49:39 INFO - 'LANG': 'en_US.UTF-8', 07:49:39 INFO - 'LANGUAGE': 'en_US:en', 07:49:39 INFO - 'LOGNAME': 'cltbld', 07:49:39 INFO - 'MAIL': '/var/mail/cltbld', 07:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:39 INFO - 'MOZ_NO_REMOTE': '1', 07:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:39 INFO - 'NO_EM_RESTART': '1', 07:49:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:39 INFO - 'PWD': '/builds/slave/test', 07:49:39 INFO - 'SHELL': '/bin/bash', 07:49:39 INFO - 'SHLVL': '1', 07:49:39 INFO - 'TERM': 'linux', 07:49:39 INFO - 'TMOUT': '86400', 07:49:39 INFO - 'USER': 'cltbld', 07:49:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:39 INFO - '_': '/tools/buildbot/bin/python'} 07:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:49:39 INFO - Cleaning up... 07:49:39 INFO - Return code: 0 07:49:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:49:39 INFO - Using env: {'DISPLAY': ':0', 07:49:39 INFO - 'HOME': '/home/cltbld', 07:49:39 INFO - 'LANG': 'en_US.UTF-8', 07:49:39 INFO - 'LANGUAGE': 'en_US:en', 07:49:39 INFO - 'LOGNAME': 'cltbld', 07:49:39 INFO - 'MAIL': '/var/mail/cltbld', 07:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:39 INFO - 'MOZ_NO_REMOTE': '1', 07:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:39 INFO - 'NO_EM_RESTART': '1', 07:49:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:39 INFO - 'PWD': '/builds/slave/test', 07:49:39 INFO - 'SHELL': '/bin/bash', 07:49:39 INFO - 'SHLVL': '1', 07:49:39 INFO - 'TERM': 'linux', 07:49:39 INFO - 'TMOUT': '86400', 07:49:39 INFO - 'USER': 'cltbld', 07:49:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:39 INFO - '_': '/tools/buildbot/bin/python'} 07:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:49:39 INFO - Cleaning up... 07:49:39 INFO - Return code: 0 07:49:39 INFO - Running post-action listener: _resource_record_post_action 07:49:39 INFO - Running post-action listener: _start_resource_monitoring 07:49:39 INFO - Starting resource monitoring. 07:49:39 INFO - [mozharness: 2016-12-26 15:49:39.648022Z] Finished create-virtualenv step (success) 07:49:39 INFO - [mozharness: 2016-12-26 15:49:39.648558Z] Running install step. 07:49:39 INFO - Running pre-action listener: _resource_record_pre_action 07:49:39 INFO - Running main action method: install 07:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:39 INFO - Reading from file tmpfile_stdout 07:49:39 INFO - Detecting whether we're running mozinstall >=1.0... 07:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:49:39 INFO - Reading from file tmpfile_stdout 07:49:39 INFO - Output received: 07:49:39 INFO - Usage: mozinstall [options] installer 07:49:39 INFO - Options: 07:49:39 INFO - -h, --help show this help message and exit 07:49:39 INFO - -d DEST, --destination=DEST 07:49:39 INFO - Directory to install application into. [default: 07:49:39 INFO - "/builds/slave/test"] 07:49:39 INFO - --app=APP Application being installed. [default: firefox] 07:49:39 INFO - mkdir: /builds/slave/test/build/application 07:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:49:56 INFO - Reading from file tmpfile_stdout 07:49:56 INFO - Output received: 07:49:56 INFO - /builds/slave/test/build/application/firefox/firefox 07:49:56 INFO - Running post-action listener: _resource_record_post_action 07:49:56 INFO - [mozharness: 2016-12-26 15:49:56.037484Z] Finished install step (success) 07:49:56 INFO - [mozharness: 2016-12-26 15:49:56.037727Z] Running run-tests step. 07:49:56 INFO - Running pre-action listener: _resource_record_pre_action 07:49:56 INFO - Running main action method: run_tests 07:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:49:56 INFO - Python 2.7.3 07:49:56 INFO - Return code: 0 07:49:56 INFO - grabbing minidump binary from tooltool 07:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441820>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1442520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14429b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:49:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:49:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:49:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:49:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:49:56 INFO - Return code: 0 07:49:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:49:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:49:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:49:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:49:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:49:56 INFO - Using env: {'DISPLAY': ':0', 07:49:56 INFO - 'HOME': '/home/cltbld', 07:49:56 INFO - 'LANG': 'en_US.UTF-8', 07:49:56 INFO - 'LANGUAGE': 'en_US:en', 07:49:56 INFO - 'LOGNAME': 'cltbld', 07:49:56 INFO - 'MAIL': '/var/mail/cltbld', 07:49:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:49:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:56 INFO - 'MOZ_NO_REMOTE': '1', 07:49:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:56 INFO - 'NO_EM_RESTART': '1', 07:49:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:56 INFO - 'PWD': '/builds/slave/test', 07:49:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:49:56 INFO - 'SHELL': '/bin/bash', 07:49:56 INFO - 'SHLVL': '1', 07:49:56 INFO - 'TERM': 'linux', 07:49:56 INFO - 'TMOUT': '86400', 07:49:56 INFO - 'USER': 'cltbld', 07:49:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221', 07:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:56 INFO - '_': '/tools/buildbot/bin/python'} 07:49:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:49:56 INFO - mozversion application_buildid: 20161226064736 07:49:56 INFO - mozversion application_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 07:49:56 INFO - mozversion application_display_name: Nightly 07:49:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:49:56 INFO - mozversion application_name: Firefox 07:49:56 INFO - mozversion application_remotingname: firefox 07:49:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:49:56 INFO - mozversion application_vendor: Mozilla 07:49:56 INFO - mozversion application_version: 53.0a1 07:49:56 INFO - mozversion platform_buildid: 20161226064736 07:49:56 INFO - mozversion platform_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 07:49:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:49:56 INFO - mozversion platform_version: 53.0a1 07:49:56 INFO - using testdate: 1482767396 07:49:56 INFO - actual date: 1482767396 07:49:56 INFO - starting webserver on 'localhost:35488' 07:49:56 INFO - SUITE-START | Running 2 tests 07:49:56 INFO - TEST-START | dromaeo_css 07:49:56 INFO - operating with platform_type : linux_ 07:49:56 INFO - Initialising browser for dromaeo_css test... 07:49:56 INFO - TEST-INFO | started process 17678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYY8F6X/profile http://localhost:35488/getInfo.html) 07:49:56 INFO - PROCESS | 17678 | 1482767396808 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:50:00 INFO - PROCESS | 17678 | __metrics Screen width/height:1600/1200 07:50:00 INFO - PROCESS | 17678 | colorDepth:24 07:50:00 INFO - PROCESS | 17678 | Browser inner width/height: 1024/697 07:50:00 INFO - PROCESS | 17678 | __metrics 07:50:01 ERROR - PROCESS | 17678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 07:50:02 INFO - TEST-INFO | 17678: exit 0 07:50:02 INFO - Browser initialized. 07:50:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:02 INFO - TEST-INFO | started process 17801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYY8F6X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:14 INFO - PROCESS | 17801 | RSS: Main: 157880320 07:50:14 INFO - PROCESS | 17801 | 07:53:17 INFO - PROCESS | 17801 | Cycle 1(1): loaded http://localhost:35488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35488/tests/dromaeo/cssquery-ext.html) 07:53:19 INFO - PROCESS | 17801 | RSS: Main: 165113856 07:53:19 INFO - PROCESS | 17801 | 07:55:02 INFO - PROCESS | 17801 | ************************* 07:55:02 INFO - PROCESS | 17801 | A coding exception was thrown and uncaught in a Task. 07:55:02 INFO - PROCESS | 17801 | 07:55:02 INFO - PROCESS | 17801 | Full message: ReferenceError: fetch is not defined 07:55:02 INFO - PROCESS | 17801 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:55:02 INFO - PROCESS | 17801 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:55:02 INFO - PROCESS | 17801 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:55:02 INFO - PROCESS | 17801 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:55:02 INFO - PROCESS | 17801 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:55:02 INFO - PROCESS | 17801 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:55:02 INFO - PROCESS | 17801 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:55:02 INFO - PROCESS | 17801 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:55:02 INFO - PROCESS | 17801 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:55:02 INFO - PROCESS | 17801 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:55:02 INFO - PROCESS | 17801 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:55:02 INFO - PROCESS | 17801 | 07:55:02 INFO - PROCESS | 17801 | ************************* 07:56:21 INFO - PROCESS | 17801 | Cycle 1(1): loaded http://localhost:35488/tests/dromaeo/cssquery-ext.html (next: http://localhost:35488/tests/dromaeo/cssquery-jquery.html) 07:56:22 INFO - PROCESS | 17801 | RSS: Main: 162582528 07:56:22 INFO - PROCESS | 17801 | 07:59:24 INFO - PROCESS | 17801 | Cycle 1(1): loaded http://localhost:35488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35488/tests/dromaeo/cssquery-mootools.html) 07:59:24 INFO - PROCESS | 17801 | RSS: Main: 167686144 07:59:24 INFO - PROCESS | 17801 | 08:02:27 INFO - PROCESS | 17801 | Cycle 1(1): loaded http://localhost:35488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35488/tests/dromaeo/cssquery-prototype.html) 08:02:27 INFO - PROCESS | 17801 | RSS: Main: 161873920 08:02:27 INFO - PROCESS | 17801 | 08:05:30 INFO - PROCESS | 17801 | Cycle 1(1): loaded http://localhost:35488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35488/tests/dromaeo/cssquery-yui.html) 08:05:31 INFO - PROCESS | 17801 | RSS: Main: 166137856 08:05:31 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | Cycle 1(1): loaded http://localhost:35488/tests/dromaeo/cssquery-yui.html (next: http://localhost:35488/tests/dromaeo/cssquery-dojo.html) 08:08:34 INFO - PROCESS | 17801 | RSS: Main: 165904384 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | __start_tp_report 08:08:34 INFO - PROCESS | 17801 | _x_x_mozilla_page_load 08:08:34 INFO - PROCESS | 17801 | _x_x_mozilla_page_load_details 08:08:34 INFO - PROCESS | 17801 | |i|pagename|runs| 08:08:34 INFO - PROCESS | 17801 | |0;dojo.html;2569.794416446684;2579.871006449677;2584.52186345526;2619.515389652915;2632.302439853438;4761.642876784246;4784.234522476405;4838.9032219355595;4842.152623290927;4892.290617860417;4774.331593576893;4789.952100479003;4802.639802014845;4808.2547205183255;4825.927611085828;4414.97792511037;4443;4520.932186017212;4521.819127234907;4586.518415566367;4484.910301793962;4535.614472769802;4571.222892108333;4591.449026116854;4595.701279416844;4416.6025057744955;4464.1071785643;4488.147252022138;4506.977465112654;4533.252013417815;4490.820367185309;4493.03399769048;4526.592606665383;4539.387182730322;4545.20458919691;4466.12910482456;4471;4481.08137831747;4507.346434766973;4511.255642818964;4665.5567721066445;4695.295705644146;4837.4920633333695;4844.20070688336;4872.025594881038;5058.038972595195;5121.385433747937;5137.974310128426;5160.974195129038;5219.5041471060185;5170.069387510247;5173.224016397533;5176.430592634807;5191.844244672666;5215.582753379758;4752.691075080109;4846.248831431134;4865.56209941107;4870.439899411613;4870.707757534559;5194.194899790463;5334.386545547234;5372.5433338166695;5384.165454354581;5465.453454654503;5166.5866730661455;5166.638335316492;5184.222366645034;5188.481151884781;5192.844214673566;5072.1123803335;5091.389033316026;5252.868678282997;5255.710935898563;5296.25852380667;5075.771590278447;5089.541941225308;5106.821261255838;5120.18077107661;5164.406093299243;5088.592912566985;5091.3126727892;5096.51583099605;5106.514881086291;5120.02719483297;4231.30183519722;4316.5467625899455;4318.52496225415;4323.481182258086;4373.650107991353;3702.5927148013698;3754.2866855297407;3768.4158955361963;3819.427085937132;3893.357595996629;4419.381286619876;4433.069055498381;4436.822527098944;4438.201123797748;4476.328550717353;5195.870103247449;5217.243499692523;5220.269162317279;5227.60792940531;5238.423773384925;4392.868213953587;4401.823927042882;4421.336799480104;4479.820807167742;4485.945802736354;4641.048585040058;4644.976775116171;4678.368420263289;4678.719276843401;4689.460712018094;4847.103285892122;4849.80600775965;4855.247436647325;4855.6601037927;4898.2652602109965;5200.479952004769;5230.424653288059;5245.291885595396;5246.921296180484;5249.921251181159;4699.553542413465;4725.50382209863;4746.0745154694505;4829.179039563212;4842.322074909586;5196.090684130338;5197.8440646780655;5202.869928251824;5217.739113044257;5241.97781432617;5253.106971814877;5271.367435907716;5286.841394758163;5298.920516192337;5334.253204551444;4909.656324057424;4935.851924442272;5074.517920797518;5147.279380886754;5153.948460515347;5089.134847075932;5130.563902068366;5162.148245539444;5184.403793563638;5190.325257716624;4835.540623640749;4868.586170175433;4871.391076115486;4917.237828136645;4986.102501549756;5049.899002020013;5067.265246539305;5071.290019397361;5073.315102461121;5146.974265128651;4855.465898751205;4891.779869905791;4906.116898958222;4936.111499930047;4993.875153121201 08:08:34 INFO - PROCESS | 17801 | |1;ext.html;509.82920721558276;525.7423862307468;529.896670149321;532.6138549551575;549.631746729691;37586.248275034486;39203.607963920316;39233.019174520654;42734.14531709364;42770.14459710804;40184.79907600458;40975.38536921948;41072.38391424131;41102.58897411025;41120.79439602805;7620.65707043183;7791.727289544867;7800.80497987552;9533.666321678742;9870.753231169243;9696.418214907128;9782.21742260621;9809;9822.312438129335;9826.754331141743;9717.368371055858;9831.213502919749;9844.114029737322;9844.70465886021;9852.064053914903;9747.75630609237;9769.560369783341;9771.804563908681;9777.608895644165;9791.853122203172;9299.953500232523;9402;9441.055894410572;9467.621295148254;9469.66856160038;34153.487697684555;34186.82906585476;34568.82715586431;34603.4809477858;34673.4798978013;3250.772445928786;3427.3133869512094;3448.258624395767;3474.6699063588917;3508.6666766657127;25378.365540861443;25920.351991200183;26378.472430551283;26608.73391266082;26885.731142688517;3295.983520082408;3350.363430948112;3378.2398960233995;3379.898603041913;3407.2844702612483;255388;255448;255853;256030;257516.99999999814;47332.76333618379;47479.52520474751;47539.76230118897;47599.762001190466;47780.5221947783;49496.50503494919;49499.752501237264;49534.75232623814;49551.50448495541;49651.50348496469;10912.399818010086;11117.332960022262;11170.664880053611;11223.775524489465;11258.43707814606;8425;8604.827903441896;8782.077881822444;8945.686900958435;9024.278057755364;15547.844521554865;15682.059076455449;15717.842821571867;15724.449644262397;15736.763948540782;6247.531435142382;6313.463355614732;6322.747090116344;6401.423871851555;6479.319671434526;780.6565111350988;789.795561768308;813.7680760983092;817.4114637461018;831.3100126894662;25121.87439062793;25333.0883484875;25512.872435637702;25922.09272675485;26348.20955371342;929.1730359979489;961.0581630002637;966.4681479854369;978.2663002747939;981.65642025292;13650.522231722041;14088.577342679737;14104.224267665171;14173.362198701083;14227.217503037433;347876;347967;351888;352900;354347;49752;50217;50250.246246306335;50382.244266336034;50399.74800125976;1201.1449798741814;1216.3370962825281;1220.5972029230334;1222.3216115056052;1227.6869398303375;25347.746522534537;25798.35504112337;26922.32694182661;27445.31386715348;27533.449331013668;41966.79016604897;42160.57839421689;42191.1561768769;42251;42258.154836903705;295035;297741;299201;300153;300964;22857.657135142654;22967.196148134735;23206.419839504146;24143.15498957528;25196.24411267665;36228.63771362253;36337.27325453529;36465.453018204214;37060;37329.44005839969;31850.20374490656;32069.51895721613;32316.35367292688;32441;32504.674953250164;31355;31747.52378714275;32229.35541289208;32969;33147;31727.36545269128;31794.68205317917;32057.153141623156;32087.518687219243;32412.675873240965;26712.866435667696;28975.42049159047;29213;29324.560131597616;29640.258993524472;22877.16982641418;22882.31353059411;22991.425214369774;23080.192193273157;23149.652755209023 08:08:34 INFO - PROCESS | 17801 | |2;jquery.html;368.5835006442721;377.51677852348996;378.3889019233938;378.867396411256;396.14432825097794;15651.686966260682;17031.48905532833;17062.402815901445;17125.828741712594;17367;16187.514374568753;16324.428644997428;16430.178491075458;16560;16940.745888811678;4987.276844857484;5175.456577059392;5203.817866374678;5207.531322181022;5224.425313215544;5078.898422031557;5110.412302585213;5389.541888939445;5399.947010332967;5474;5406.053940560386;5424.647397919142;5432.728363581825;5440.591955603346;5444.292242008533;5377.274068000828;6078.331383547807;6163.630182189083;6163.999999999977;6165.229346331708;5219.295395121686;5474.39781624021;5492.780288788444;5499.092649712793;5500.28496295479;12365.505379784798;12649.810252846215;12655.303958282291;12660.62018139448;12666.936665316707;2337.941551461227;2385.4751954569965;2424.903003879843;2429.97570024301;2433.330834020641;9085.545722713838;9147.22248608869;9187;9525.19035881951;9636.566354514063;1684.3010150787409;1734.5576877896183;1738.1830539646346;1765.8145894681002;1769.0977601423238;59383;60676.745716736266;64493.35506644967;70909.99999999949;72996.27003729947;9286.164245217962;9296;9572.186364158984;9621.230301575857;9765.804683906283;12786.872131278755;12864.16382935125;12932;12954.481820727065;12970.481180752853;6384.7126879290545;6393.648349340738;6630.602163870183;6690.799276021728;6692.899606505906;6025.307089684742;6100.908486372708;6237.812865614039;6240.095186197976;6256.468200203034;3878.650921417086;3952.4268980998168;4135.234981528367;4139.627433530996;4237.512686041083;1591.0692245036782;1593.7768712380275;1810.6107186954869;1851.8425933795509;1881.1628825172668;897.6678628907346;909.0863681999548;917.6971599372191;936.5551363102472;952.1335584617964;15561.610959725871;15728.05631662104;15984.200789960456;16038;16096;898.23201163006;900.8153328567628;903.2367649336301;912.1608120528997;914.7575892388522;8882.289416846637;8917.464952102895;8920.776980575538;8948.418352807046;9193.540322983825;349809;353544;357980;358686;360909;71495;71602;71705.6414717923;73877;74374.25625743781;845.5011543189547;859.3554833874595;859.6475445067492;859.9871001934976;863.7495126413395;13899.583012509642;13954.441822326993;13961.301934903011;14004.448554219716;14581.562553123424;34574.30851383009;35701;35757.28485430329;36017.099572510895;36205.63794362128;45008.77495612501;45409.31886021646;46143.538564613926;48143;52090.73954630203;7516.661750221362;7632.542047477169;7700.961495192488;7775.57234352116;7988.920110798817;11621.6513504595;12305.56930507428;12414;12430.192037517532;12534.623961281177;9857.309988301036;9947.602095916081;10019.799604008025;10219.693409197736;10430.113440357654;9955.850662239927;10026.94986525092;10486.632967846088;10493.580256789643;10501;9741.853872192065;9816.460094694861;10035.69892903214;10338.844917326396;10365.63720269787;7547.320741133324;7557.470977031555;7628.237176282328;7656.4640475168435;7720.3437707795465;7490.063742032246;8543.40196186261;9630.422174669542;9741.26940479481;9914.30599858032 08:08:34 INFO - PROCESS | 17801 | |3;mootools.html;1323.6558494791357;1323.9470421183153;1339.564641491515;1340.9798853017212;1348.5819395987248;6394.136791533142;6606.669666516679;6856.451483881284;7262.346388825004;7268.200497945229;6776.864462710742;7062.470314726404;7074.540154889942;7226.638668066602;7226.819329516765;2112.598606264805;2141.5609799990966;2141.6787481877686;2142.2180903970093;2150.1721837092755;2130.1266480742897;2137.967930481036;2184.290105715641;2195.9780402195934;2215.7451893032267;2157.6439887418555;2186.9562608747815;2188.3872515695552;2189.343197040889;2192.177933275022;2041.3263623004373;2076.802703743157;2080.5942841145984;2085.2180432337873;2087.123408168573;2136.647453170225;2138.2088627207877;2147.7207962964712;2150.2044243629957;2157.8273738100916;7220.92779072213;7257.237990011025;7287.854242915165;7355.080614923135;7366.373858222004;4358.629516491071;4510.368548403226;4517.864464066083;4522.140793249272;4528.18492671319;2883.8413887236193;3021.6978302169828;3093.257618171645;3122.406742718876;3123.7813353065294;1113.6492005018392;1175.048210949134;1180.3331117918349;1192.493190394079;1661.6012157082334;9055.773605659846;9142.771430714285;9194.632214711404;9220.769480762967;9232.492212928286;3801.7528860624525;3950.1902110067367;4019.879403617896;4028.113814960704;4044.7977601120415;7700.884486732703;7732.38140948734;7737.6518056686455;7743.419243556756;7747.767566973;4307.763073030951;4324;4329.805158767863;4335.154644844288;4337.718048326912;3837.9616203838164;3916.921661566753;3965.1673148638533;4037.959620403817;4045.554988951213;3156.2898347871546;3198.712115909579;3266.3467306538787;3268.7548433867555;3287.506873968876;981.788915383196;1003.2575893838507;1029.562435964712;1030.1552726763982;1045;641.0864518061836;647.9092926990131;801.7594721583501;806.7700705298962;840.8906842110487;4816.060868130681;4842.201036828884;4860.538248866423;4972.90054198914;5016.899662006739;828.7513745876213;829.8838162657232;837.9664813407395;852.86780549015;859.3254295378098;3271.2966712156526;3418.213810823475;3452.5856897172;3494.3884820156372;3587.9103022424647;8168.714095006646;8459.154084591615;8534.487930724052;8546.273566746771;8574.571271436404;8256.215659512336;8388.245057944814;8459.111793261742;8501.61742721579;8506.404551681446;6665.833354166185;6725.663716814238;6796.72813087471;6801.625910574966;6833.863322733518;4532.5694059064335;4822.758862056813;4877.926831097608;4933.062718083553;5054.368203974504;7794.259545343183;7844.882326765217;7845.843083138191;7918.208179182036;7969;7905.762827115196;7977.641006154621;7978.441509094308;8132.796680083045;8149.348052155932;5764.798232061858;5875.50058245054;5904.940950590439;5914.3198532167635;5915.994280972331;6724.7646332378135;6778.559393639398;6793.558418702769;6823.624700641512;6901.930980690129;5268.6575372600655;6028.2766068072115;6055.061465472859;6099.115628233792;6173.783917562864;5951.315598706205;5987.2815262168815;6013.218281623536;6023.849403764941;6153.046277826944;5773.220615216892;5827.737751801094;5879.412058794086;5889;5910.911336329872;4287.099709061132;4307.39696442492;4385.890352741207;4433.911321773611;4501.07727915783;4359.498657654283;4364.061726728769;4398.890027749204;4438.977805110953;4453.710508816916 08:08:34 INFO - PROCESS | 17801 | |4;prototype.html;358.3639040702752;362.8639260277396;366.2345697492241;366.5015578812816;370.39070728651353;4935.383077115361;4985.7784842713545;4991.755359016641;5009.649324547283;5032.924506132411;4945.109880221558;4953.331300274462;4980.153373926432;4985.501449855022;5002.824336280971;3533.2050288685095;3820.656140947321;3924.862629807957;3970.9801450992704;4012.9598704013024;3974.304496713078;3995;3997.4203740457615;4007.5792041835625;4024.738392004525;3995.240904228202;3999.7200195986293;4012.879613611596;4014.799260037001;4018.97990510047;3638.854445822164;4029.9597004030024;4033.415154802537;4033.6974726895455;4033.8184781684895;3347.2301370684636;3357.6474470180524;3366.1584603849037;3371.4100032494216;3397.6092749333898;4754.9286760698615;4763.666543341968;4787.13831510328;4817.879553011203;4846.418429788448;4386.670999675037;4405.801738921757;4412.470503539564;4414.719731277925;4415.51429342772;4914.533119353656;5110.565601923841;5158.226266060084;5184.7407629618365;5213.635045546814;3682.287736202652;3691.6123807000145;3693.519842420495;3718.8140592970244;3723.962760372389;5212.592599997979;5427.565794736412;5448.945510544923;5463.863403414946;5477.534409575191;3730.720195985312;3736.3461394255896;3756.887293381203;3763.247350529905;3767.9058023549633;3813.466114743938;3819.255245227154;3828.8277027533827;3836.731428800013;3840.539135303781;4272.636825869774;4367.825286988485;4398.164348773723;4398.64810815134;4433.1577000369825;5196.714180720021;5226.947730522646;5229.241759944863;5236.711980841091;5246.23929530216;1283.2685369339388;1289.239348784203;1291.4051146833654;1294.5792617399384;1302.9022823288292;829.8838162657232;882.4484697064335;886.7472770260445;891.2558014058334;892.1034360467688;4368.847090351823;4421.204183247047;4424.358468022118;4424.955750442518;4439.644828413784;5188.92216616751;5260.0531904257605;5268.604854635918;5273.419923808378;5305.602079844027;4219.198352313112;4427.867163985021;4428.955710442919;4436.3567282743825;4452.243118669834;2747.763506422118;2774.764145047653;2776;2783.930401739932;2790.897595449786;4809.278608208797;4818.012307476959;4826.493218212037;4832.299316599074;4856.587190088811;5244.659097158748;5387.542058925035;5390.353157621111;5414;5417.972910135503;4764.213904705824;4775.904481910412;4790.8323208687525;4802;4845.442774080955;5152.27868098474;5192.688438693691;5192.9740351299515;5199.142141546602;5218.582513398995;5032.6225533084435;5081.390233172043;5119.539241468286;5160.200168973815;5248.291480650063;4798.3042458843975;4966.826161084345;4979.751012449436;4989.2516122580455;5050.595952323879;3746.2507498499863;3770.905727356838;3774.5281839770028;3780.4140358244517;3788.677962373119;5386.380566234933;5396.9190462142315;5397.676139431646;5406.21609866575;5426.18607208906;5015.3730783652045;5214.322138122172;5231.764570594256;5260.842174734764;5270.815521456884;5217.217417387422;5226.895462090661;5227.843164705065;5242.501962313574;5245.318108645852;5143.4085080216255;5186.196139598368;5188.325517682677;5205.089109405763;5207.27098206259;5042.899142017066;5254.292354984722;5274.393444753749;5276.419593844751;5302.28419163408;5143.254228136824;5147.485251474973;5154.7938082476285;5192.169252919514;5192.3769147701005 08:08:34 INFO - PROCESS | 17801 | |5;yui.html;658.6311665467342;669.34069941108;726.7020521586147;730.3062091013537;773.3836282170265;12372.443240054197;13983.580492585214;14704;15109.320080596372;15376.769348459782;14216.715665686681;14821.40714371424;15057.39770409185;15276.618084547892;15346.616334591641;806.1857523900858;829.4318391901537;830.8089139497919;831.3349320543571;833.3374966901328;789.6051974012994;824.9298809601161;824.9793755156109;825.781167990483;826.7271800305884;853.8335024670192;855.3798496090347;855.4268640011207;855.6534603485599;855.8373908957309;845.8688903220011;846.272205902923;847.5423271433418;849.7450764770575;851.1233429567525;621.2048577820398;621.8880601491729;622.2532960447481;622.9345918678519;623.4295619508144;13527.864721352758;13782.93108534461;13785.035047546677;13803.585892423243;14182.290885455686;13201.471941122343;14000.509982150576;14063.36714847824;14251.429942802275;14265.857341426557;10263.332883362558;10384.117350025257;10466.319689220252;10526.894731052744;10716.035556799925;3119.5788568543185;3153.2905096353365;3159.5892533970436;3160.2573395252098;3174.825384603846;62093.379066209214;62977.68511157461;63031.369686303005;64823.67588162076;66636.6668166661;10140.442275674908;10172.3724204127;10230.130438912776;10278.17774578047;10341.586336546605;20708.689369659467;20738.688919666216;20763.79236207649;20766.896165519378;20824.27115050966;6287.371262873676;6367.395097465734;6470.838229044218;6560.376764207393;6561.40947314744;3365.7139143173104;3415.590129184514;3442.6901578858024;3482.7736197147105;3499.842507087187;3077.569140320357;3127.46833038384;3159.6524382317925;3171.223050352632;3180.9363812724077;532.1618450939785;552.9972350138304;555.2088274209235;556.1407625219047;561.3432284227464;524.7376311844077;527.2197148220655;528.1417696243604;535.130413078747;541.2152379050393;7817.5309481431295;8379.413441059067;9044.728658140266;9397.57710903011;9658.565364558612;481.9036192761462;491.87211325055745;497.62926619668576;537.6263496869716;550.243415303957;3634.8546058157376;3945.1320709443876;4047.372657238133;4061.3501839705505;4067.074899641329;61115;61866;61992.69003654953;62690;63779.68110159419;55084.44915550874;55726.72136639291;56612;56726.7163664179;57637.71181144068;47872.281915771295;49301.26048109281;50187.74906125446;51418.228726569134;51834.48165518372;7294.927050729425;7686.615669216629;7729.6521656524465;7806.336461400844;7906.920930790849;34962;35206.82396588001;35559.288814224084;35908.820455897556;36167.81916090403;41312;42908.35637465503;42998.78500607602;43060;45270.77364613156;9235.445873247627;9630;9631.9036809631;9664.613415463302;9752.85370719454;10695.786084278116;10740.892591074302;10765.3540787553;10820.4048777318;10853.294535855144;10070.899291006996;10085.848712269175;10218.386896786216;10292.588296468057;10417.583296668352;9349.205317548085;9417.529123543933;9479.668211612561;9746.805063898824;9754.658586949423;10202;10215.284930054824;10313.74215644615;10385.273030888067;10407.323523970917;8151.429399941947;8234.876476852733;8348.290395316224;8441.240288374076;8535.3598480113;7784.146847310618;8017.358611311198;8136.389770767098;8295.336373090258;9999.300118979645 08:08:34 INFO - PROCESS | 17801 | __end_tp_report 08:08:34 INFO - PROCESS | 17801 | __start_cc_report 08:08:34 INFO - PROCESS | 17801 | _x_x_mozilla_cycle_collect,329 08:08:34 INFO - PROCESS | 17801 | __end_cc_report 08:08:34 INFO - PROCESS | 17801 | __startTimestamp1482768514358__endTimestamp 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | ------- Summary: start ------- 08:08:34 INFO - PROCESS | 17801 | Number of tests: 6 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | [#0] dojo.html Cycles:165 Average:4786.83 Median:4868.59 stddev:524.08 (10.8%) stddev-sans-first:495.99 08:08:34 INFO - PROCESS | 17801 | Values: 2569.8 2579.9 2584.5 2619.5 2632.3 4761.6 4784.2 4838.9 4842.2 4892.3 4774.3 4790.0 4802.6 4808.3 4825.9 4415.0 4443.0 4520.9 4521.8 4586.5 4484.9 4535.6 4571.2 4591.4 4595.7 4416.6 4464.1 4488.1 4507.0 4533.3 4490.8 4493.0 4526.6 4539.4 4545.2 4466.1 4471.0 4481.1 4507.3 4511.3 4665.6 4695.3 4837.5 4844.2 4872.0 5058.0 5121.4 5138.0 5161.0 5219.5 5170.1 5173.2 5176.4 5191.8 5215.6 4752.7 4846.2 4865.6 4870.4 4870.7 5194.2 5334.4 5372.5 5384.2 5465.5 5166.6 5166.6 5184.2 5188.5 5192.8 5072.1 5091.4 5252.9 5255.7 5296.3 5075.8 5089.5 5106.8 5120.2 5164.4 5088.6 5091.3 5096.5 5106.5 5120.0 4231.3 4316.5 4318.5 4323.5 4373.7 3702.6 3754.3 3768.4 3819.4 3893.4 4419.4 4433.1 4436.8 4438.2 4476.3 5195.9 5217.2 5220.3 5227.6 5238.4 4392.9 4401.8 4421.3 4479.8 4485.9 4641.0 4645.0 4678.4 4678.7 4689.5 4847.1 4849.8 4855.2 4855.7 4898.3 5200.5 5230.4 5245.3 5246.9 5249.9 4699.6 4725.5 4746.1 4829.2 4842.3 5196.1 5197.8 5202.9 5217.7 5242.0 5253.1 5271.4 5286.8 5298.9 5334.3 4909.7 4935.9 5074.5 5147.3 5153.9 5089.1 5130.6 5162.1 5184.4 5190.3 4835.5 4868.6 4871.4 4917.2 4986.1 5049.9 5067.3 5071.3 5073.3 5147.0 4855.5 4891.8 4906.1 4936.1 4993.9 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | [#1] ext.html Cycles:180 Average:44764.16 Median:175737.83 stddev:79960.62 (45.5%) stddev-sans-first:80115.90 08:08:34 INFO - PROCESS | 17801 | Values: 509.8 525.7 529.9 532.6 549.6 37586.2 39203.6 39233.0 42734.1 42770.1 40184.8 40975.4 41072.4 41102.6 41120.8 7620.7 7791.7 7800.8 9533.7 9870.8 9696.4 9782.2 9809.0 9822.3 9826.8 9717.4 9831.2 9844.1 9844.7 9852.1 9747.8 9769.6 9771.8 9777.6 9791.9 9300.0 9402.0 9441.1 9467.6 9469.7 34153.5 34186.8 34568.8 34603.5 34673.5 3250.8 3427.3 3448.3 3474.7 3508.7 25378.4 25920.4 26378.5 26608.7 26885.7 3296.0 3350.4 3378.2 3379.9 3407.3 255388.0 255448.0 255853.0 256030.0 257517.0 47332.8 47479.5 47539.8 47599.8 47780.5 49496.5 49499.8 49534.8 49551.5 49651.5 10912.4 11117.3 11170.7 11223.8 11258.4 8425.0 8604.8 8782.1 8945.7 9024.3 15547.8 15682.1 15717.8 15724.4 15736.8 6247.5 6313.5 6322.7 6401.4 6479.3 780.7 789.8 813.8 817.4 831.3 25121.9 25333.1 25512.9 25922.1 26348.2 929.2 961.1 966.5 978.3 981.7 13650.5 14088.6 14104.2 14173.4 14227.2 347876.0 347967.0 351888.0 352900.0 354347.0 49752.0 50217.0 50250.2 50382.2 50399.7 1201.1 1216.3 1220.6 1222.3 1227.7 25347.7 25798.4 26922.3 27445.3 27533.4 41966.8 42160.6 42191.2 42251.0 42258.2 295035.0 297741.0 299201.0 300153.0 300964.0 22857.7 22967.2 23206.4 24143.2 25196.2 36228.6 36337.3 36465.5 37060.0 37329.4 31850.2 32069.5 32316.4 32441.0 32504.7 31355.0 31747.5 32229.4 32969.0 33147.0 31727.4 31794.7 32057.2 32087.5 32412.7 26712.9 28975.4 29213.0 29324.6 29640.3 22877.2 22882.3 22991.4 23080.2 23149.7 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | [#2] jquery.html Cycles:180 Average:22618.63 Median:28655.02 stddev:58881.10 (205.5%) stddev-sans-first:59022.58 08:08:34 INFO - PROCESS | 17801 | Values: 368.6 377.5 378.4 378.9 396.1 15651.7 17031.5 17062.4 17125.8 17367.0 16187.5 16324.4 16430.2 16560.0 16940.7 4987.3 5175.5 5203.8 5207.5 5224.4 5078.9 5110.4 5389.5 5399.9 5474.0 5406.1 5424.6 5432.7 5440.6 5444.3 5377.3 6078.3 6163.6 6164.0 6165.2 5219.3 5474.4 5492.8 5499.1 5500.3 12365.5 12649.8 12655.3 12660.6 12666.9 2337.9 2385.5 2424.9 2430.0 2433.3 9085.5 9147.2 9187.0 9525.2 9636.6 1684.3 1734.6 1738.2 1765.8 1769.1 59383.0 60676.7 64493.4 70910.0 72996.3 9286.2 9296.0 9572.2 9621.2 9765.8 12786.9 12864.2 12932.0 12954.5 12970.5 6384.7 6393.6 6630.6 6690.8 6692.9 6025.3 6100.9 6237.8 6240.1 6256.5 3878.7 3952.4 4135.2 4139.6 4237.5 1591.1 1593.8 1810.6 1851.8 1881.2 897.7 909.1 917.7 936.6 952.1 15561.6 15728.1 15984.2 16038.0 16096.0 898.2 900.8 903.2 912.2 914.8 8882.3 8917.5 8920.8 8948.4 9193.5 349809.0 353544.0 357980.0 358686.0 360909.0 71495.0 71602.0 71705.6 73877.0 74374.3 845.5 859.4 859.6 860.0 863.7 13899.6 13954.4 13961.3 14004.4 14581.6 34574.3 35701.0 35757.3 36017.1 36205.6 45008.8 45409.3 46143.5 48143.0 52090.7 7516.7 7632.5 7701.0 7775.6 7988.9 11621.7 12305.6 12414.0 12430.2 12534.6 9857.3 9947.6 10019.8 10219.7 10430.1 9955.9 10026.9 10486.6 10493.6 10501.0 9741.9 9816.5 10035.7 10338.8 10365.6 7547.3 7557.5 7628.2 7656.5 7720.3 7490.1 8543.4 9630.4 9741.3 9914.3 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | [#3] mootools.html Cycles:180 Average:4697.50 Median:4952.98 stddev:2469.93 (49.9%) stddev-sans-first:2463.85 08:08:34 INFO - PROCESS | 17801 | Values: 1323.7 1323.9 1339.6 1341.0 1348.6 6394.1 6606.7 6856.5 7262.3 7268.2 6776.9 7062.5 7074.5 7226.6 7226.8 2112.6 2141.6 2141.7 2142.2 2150.2 2130.1 2138.0 2184.3 2196.0 2215.7 2157.6 2187.0 2188.4 2189.3 2192.2 2041.3 2076.8 2080.6 2085.2 2087.1 2136.6 2138.2 2147.7 2150.2 2157.8 7220.9 7257.2 7287.9 7355.1 7366.4 4358.6 4510.4 4517.9 4522.1 4528.2 2883.8 3021.7 3093.3 3122.4 3123.8 1113.6 1175.0 1180.3 1192.5 1661.6 9055.8 9142.8 9194.6 9220.8 9232.5 3801.8 3950.2 4019.9 4028.1 4044.8 7700.9 7732.4 7737.7 7743.4 7747.8 4307.8 4324.0 4329.8 4335.2 4337.7 3838.0 3916.9 3965.2 4038.0 4045.6 3156.3 3198.7 3266.3 3268.8 3287.5 981.8 1003.3 1029.6 1030.2 1045.0 641.1 647.9 801.8 806.8 840.9 4816.1 4842.2 4860.5 4972.9 5016.9 828.8 829.9 838.0 852.9 859.3 3271.3 3418.2 3452.6 3494.4 3587.9 8168.7 8459.2 8534.5 8546.3 8574.6 8256.2 8388.2 8459.1 8501.6 8506.4 6665.8 6725.7 6796.7 6801.6 6833.9 4532.6 4822.8 4877.9 4933.1 5054.4 7794.3 7844.9 7845.8 7918.2 7969.0 7905.8 7977.6 7978.4 8132.8 8149.3 5764.8 5875.5 5904.9 5914.3 5916.0 6724.8 6778.6 6793.6 6823.6 6901.9 5268.7 6028.3 6055.1 6099.1 6173.8 5951.3 5987.3 6013.2 6023.8 6153.0 5773.2 5827.7 5879.4 5889.0 5910.9 4287.1 4307.4 4385.9 4433.9 4501.1 4359.5 4364.1 4398.9 4439.0 4453.7 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | [#4] prototype.html Cycles:180 Average:4294.12 Median:4822.25 stddev:1239.39 (25.7%) stddev-sans-first:1207.15 08:08:34 INFO - PROCESS | 17801 | Values: 358.4 362.9 366.2 366.5 370.4 4935.4 4985.8 4991.8 5009.6 5032.9 4945.1 4953.3 4980.2 4985.5 5002.8 3533.2 3820.7 3924.9 3971.0 4013.0 3974.3 3995.0 3997.4 4007.6 4024.7 3995.2 3999.7 4012.9 4014.8 4019.0 3638.9 4030.0 4033.4 4033.7 4033.8 3347.2 3357.6 3366.2 3371.4 3397.6 4754.9 4763.7 4787.1 4817.9 4846.4 4386.7 4405.8 4412.5 4414.7 4415.5 4914.5 5110.6 5158.2 5184.7 5213.6 3682.3 3691.6 3693.5 3718.8 3724.0 5212.6 5427.6 5448.9 5463.9 5477.5 3730.7 3736.3 3756.9 3763.2 3767.9 3813.5 3819.3 3828.8 3836.7 3840.5 4272.6 4367.8 4398.2 4398.6 4433.2 5196.7 5226.9 5229.2 5236.7 5246.2 1283.3 1289.2 1291.4 1294.6 1302.9 829.9 882.4 886.7 891.3 892.1 4368.8 4421.2 4424.4 4425.0 4439.6 5188.9 5260.1 5268.6 5273.4 5305.6 4219.2 4427.9 4429.0 4436.4 4452.2 2747.8 2774.8 2776.0 2783.9 2790.9 4809.3 4818.0 4826.5 4832.3 4856.6 5244.7 5387.5 5390.4 5414.0 5418.0 4764.2 4775.9 4790.8 4802.0 4845.4 5152.3 5192.7 5193.0 5199.1 5218.6 5032.6 5081.4 5119.5 5160.2 5248.3 4798.3 4966.8 4979.8 4989.3 5050.6 3746.3 3770.9 3774.5 3780.4 3788.7 5386.4 5396.9 5397.7 5406.2 5426.2 5015.4 5214.3 5231.8 5260.8 5270.8 5217.2 5226.9 5227.8 5242.5 5245.3 5143.4 5186.2 5188.3 5205.1 5207.3 5042.9 5254.3 5274.4 5276.4 5302.3 5143.3 5147.5 5154.8 5192.2 5192.4 08:08:34 INFO - PROCESS | 17801 | 08:08:34 INFO - PROCESS | 17801 | [#5] yui.html Cycles:180 Average:14445.02 Median:530.15 stddev:18013.12 (3397.7%) stddev-sans-first:18033.90 08:08:34 INFO - PROCESS | 17801 | Values: 658.6 669.3 726.7 730.3 773.4 12372.4 13983.6 14704.0 15109.3 15376.8 14216.7 14821.4 15057.4 15276.6 15346.6 806.2 829.4 830.8 831.3 833.3 789.6 824.9 825.0 825.8 826.7 853.8 855.4 855.4 855.7 855.8 845.9 846.3 847.5 849.7 851.1 621.2 621.9 622.3 622.9 623.4 13527.9 13782.9 13785.0 13803.6 14182.3 13201.5 14000.5 14063.4 14251.4 14265.9 10263.3 10384.1 10466.3 10526.9 10716.0 3119.6 3153.3 3159.6 3160.3 3174.8 62093.4 62977.7 63031.4 64823.7 66636.7 10140.4 10172.4 10230.1 10278.2 10341.6 20708.7 20738.7 20763.8 20766.9 20824.3 6287.4 6367.4 6470.8 6560.4 6561.4 3365.7 3415.6 3442.7 3482.8 3499.8 3077.6 3127.5 3159.7 3171.2 3180.9 532.2 553.0 555.2 556.1 561.3 524.7 527.2 528.1 535.1 541.2 7817.5 8379.4 9044.7 9397.6 9658.6 481.9 491.9 497.6 537.6 550.2 3634.9 3945.1 4047.4 4061.4 4067.1 61115.0 61866.0 61992.7 62690.0 63779.7 55084.4 55726.7 56612.0 56726.7 57637.7 47872.3 49301.3 50187.7 51418.2 51834.5 7294.9 7686.6 7729.7 7806.3 7906.9 34962.0 35206.8 35559.3 35908.8 36167.8 41312.0 42908.4 42998.8 43060.0 45270.8 9235.4 9630.0 9631.9 9664.6 9752.9 10695.8 10740.9 10765.4 10820.4 10853.3 10070.9 10085.8 10218.4 10292.6 10417.6 9349.2 9417.5 9479.7 9746.8 9754.7 10202.0 10215.3 10313.7 10385.3 10407.3 8151.4 8234.9 8348.3 8441.2 8535.4 7784.1 8017.4 8136.4 8295.3 9999.3 08:08:34 INFO - PROCESS | 17801 | -------- Summary: end -------- 08:08:34 INFO - PROCESS | 17801 | 08:08:35 INFO - TEST-INFO | 17801: exit 0 08:08:35 INFO - TEST-OK | dromaeo_css | took 1118923ms 08:08:35 INFO - TEST-START | kraken 08:08:35 INFO - operating with platform_type : linux_ 08:08:35 INFO - Initialising browser for kraken test... 08:08:35 INFO - TEST-INFO | started process 18152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxzi0ss/profile http://localhost:35488/getInfo.html) 08:08:35 INFO - PROCESS | 18152 | 1482768515715 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:08:39 INFO - PROCESS | 18152 | __metrics Screen width/height:1600/1200 08:08:39 INFO - PROCESS | 18152 | colorDepth:24 08:08:39 INFO - PROCESS | 18152 | Browser inner width/height: 1024/697 08:08:39 INFO - PROCESS | 18152 | __metrics 08:08:40 ERROR - PROCESS | 18152 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 08:08:41 INFO - TEST-INFO | 18152: exit 0 08:08:41 INFO - Browser initialized. 08:08:41 INFO - Running cycle 1/1 for kraken test... 08:08:41 INFO - TEST-INFO | started process 18275 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxzi0ss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:53 INFO - PROCESS | 18275 | RSS: Main: 158101504 08:08:53 INFO - PROCESS | 18275 | 08:08:56 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35488/tests/kraken/driver.html?testName=audio-beat-detection) 08:08:57 INFO - PROCESS | 18275 | RSS: Main: 163934208 08:08:57 INFO - PROCESS | 18275 | 08:08:59 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35488/tests/kraken/driver.html?testName=audio-dft) 08:09:00 INFO - PROCESS | 18275 | RSS: Main: 162742272 08:09:00 INFO - PROCESS | 18275 | 08:09:03 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35488/tests/kraken/driver.html?testName=audio-fft) 08:09:03 INFO - PROCESS | 18275 | RSS: Main: 159391744 08:09:03 INFO - PROCESS | 18275 | 08:09:06 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35488/tests/kraken/driver.html?testName=audio-oscillator) 08:09:06 INFO - PROCESS | 18275 | RSS: Main: 160055296 08:09:06 INFO - PROCESS | 18275 | 08:09:08 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:09:09 INFO - PROCESS | 18275 | RSS: Main: 160280576 08:09:09 INFO - PROCESS | 18275 | 08:09:12 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35488/tests/kraken/driver.html?testName=imaging-darkroom) 08:09:13 INFO - PROCESS | 18275 | RSS: Main: 164442112 08:09:13 INFO - PROCESS | 18275 | 08:09:16 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35488/tests/kraken/driver.html?testName=imaging-desaturate) 08:09:17 INFO - PROCESS | 18275 | RSS: Main: 161886208 08:09:17 INFO - PROCESS | 18275 | 08:09:20 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35488/tests/kraken/driver.html?testName=json-parse-financial) 08:09:21 INFO - PROCESS | 18275 | RSS: Main: 162394112 08:09:21 INFO - PROCESS | 18275 | 08:09:22 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:09:23 INFO - PROCESS | 18275 | RSS: Main: 163487744 08:09:23 INFO - PROCESS | 18275 | 08:09:25 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:25 INFO - PROCESS | 18275 | RSS: Main: 162811904 08:09:25 INFO - PROCESS | 18275 | 08:09:28 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:28 INFO - PROCESS | 18275 | RSS: Main: 163176448 08:09:28 INFO - PROCESS | 18275 | 08:09:31 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:09:32 INFO - PROCESS | 18275 | RSS: Main: 163774464 08:09:32 INFO - PROCESS | 18275 | 08:09:35 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:09:36 INFO - PROCESS | 18275 | RSS: Main: 163078144 08:09:36 INFO - PROCESS | 18275 | 08:09:37 INFO - PROCESS | 18275 | Cycle 1(1): loaded http://localhost:35488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35488/tests/kraken/driver.html?testName=ai-astar) 08:09:38 INFO - PROCESS | 18275 | RSS: Main: 164954112 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | __start_tp_report 08:09:38 INFO - PROCESS | 18275 | _x_x_mozilla_page_load 08:09:38 INFO - PROCESS | 18275 | _x_x_mozilla_page_load_details 08:09:38 INFO - PROCESS | 18275 | |i|pagename|runs| 08:09:38 INFO - PROCESS | 18275 | |0;ai-astar;109;118;115;115;115;115;115;119;116;117 08:09:38 INFO - PROCESS | 18275 | |1;audio-beat-detection;152;127;127;185;132;128;129;128;148;128 08:09:38 INFO - PROCESS | 18275 | |2;audio-dft;165;167;173;164;164;165;162;164;162;167 08:09:38 INFO - PROCESS | 18275 | |3;audio-fft;77;77;77;120;78;78;77;76;98;78 08:09:38 INFO - PROCESS | 18275 | |4;audio-oscillator;85;86;110;86;86;85;98;85;85;103 08:09:38 INFO - PROCESS | 18275 | |5;imaging-gaussian-blur;117;117;118;118;118;124;118;118;118;117 08:09:38 INFO - PROCESS | 18275 | |6;imaging-darkroom;150;150;152;150;150;151;150;150;152;152 08:09:38 INFO - PROCESS | 18275 | |7;imaging-desaturate;98;96;95;96;96;99;96;96;96;95 08:09:38 INFO - PROCESS | 18275 | |8;json-parse-financial;65;66;66;66;70;67;67;73;66;66 08:09:38 INFO - PROCESS | 18275 | |9;json-stringify-tinderbox;68;70;68;68;68;68;69;67;68;68 08:09:38 INFO - PROCESS | 18275 | |10;stanford-crypto-aes;84;97;87;86;85;86;89;86;95;85 08:09:38 INFO - PROCESS | 18275 | |11;stanford-crypto-ccm;151;143;137;142;134;145;145;144;216;132 08:09:38 INFO - PROCESS | 18275 | |12;stanford-crypto-pbkdf2;185;185;181;183;189;182;188;184;184;186 08:09:38 INFO - PROCESS | 18275 | |13;stanford-crypto-sha256-iterative;74;70;72;69;69;70;69;70;69;73 08:09:38 INFO - PROCESS | 18275 | __end_tp_report 08:09:38 INFO - PROCESS | 18275 | __start_cc_report 08:09:38 INFO - PROCESS | 18275 | _x_x_mozilla_cycle_collect,803 08:09:38 INFO - PROCESS | 18275 | __end_cc_report 08:09:38 INFO - PROCESS | 18275 | __startTimestamp1482768578248__endTimestamp 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | ------- Summary: start ------- 08:09:38 INFO - PROCESS | 18275 | Number of tests: 14 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#0] ai-astar Cycles:10 Average:115.40 Median:115.50 stddev:2.67 (2.3%) stddev-sans-first:1.54 08:09:38 INFO - PROCESS | 18275 | Values: 109.0 118.0 115.0 115.0 115.0 115.0 115.0 119.0 116.0 117.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:130.50 stddev:18.75 (14.4%) stddev-sans-first:19.23 08:09:38 INFO - PROCESS | 18275 | Values: 152.0 127.0 127.0 185.0 132.0 128.0 129.0 128.0 148.0 128.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#2] audio-dft Cycles:10 Average:165.30 Median:165.00 stddev:3.20 (1.9%) stddev-sans-first:3.39 08:09:38 INFO - PROCESS | 18275 | Values: 165.0 167.0 173.0 164.0 164.0 165.0 162.0 164.0 162.0 167.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#3] audio-fft Cycles:10 Average:83.60 Median:77.50 stddev:14.37 (18.5%) stddev-sans-first:15.04 08:09:38 INFO - PROCESS | 18275 | Values: 77.0 77.0 77.0 120.0 78.0 78.0 77.0 76.0 98.0 78.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:85.50 stddev:9.27 (10.8%) stddev-sans-first:9.58 08:09:38 INFO - PROCESS | 18275 | Values: 85.0 86.0 110.0 86.0 86.0 85.0 98.0 85.0 85.0 103.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.00 stddev:2.06 (1.7%) stddev-sans-first:2.13 08:09:38 INFO - PROCESS | 18275 | Values: 117.0 117.0 118.0 118.0 118.0 124.0 118.0 118.0 118.0 117.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:150.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 08:09:38 INFO - PROCESS | 18275 | Values: 150.0 150.0 152.0 150.0 150.0 151.0 150.0 150.0 152.0 152.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:1.25 (1.3%) stddev-sans-first:1.17 08:09:38 INFO - PROCESS | 18275 | Values: 98.0 96.0 95.0 96.0 96.0 99.0 96.0 96.0 96.0 95.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:66.50 stddev:2.44 (3.7%) stddev-sans-first:2.46 08:09:38 INFO - PROCESS | 18275 | Values: 65.0 66.0 66.0 66.0 70.0 67.0 67.0 73.0 66.0 66.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 08:09:38 INFO - PROCESS | 18275 | Values: 68.0 70.0 68.0 68.0 68.0 68.0 69.0 67.0 68.0 68.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:86.50 stddev:4.45 (5.1%) stddev-sans-first:4.48 08:09:38 INFO - PROCESS | 18275 | Values: 84.0 97.0 87.0 86.0 85.0 86.0 89.0 86.0 95.0 85.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:144.50 stddev:24.25 (16.8%) stddev-sans-first:25.71 08:09:38 INFO - PROCESS | 18275 | Values: 151.0 143.0 137.0 142.0 134.0 145.0 145.0 144.0 216.0 132.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.70 Median:185.00 stddev:2.50 (1.3%) stddev-sans-first:2.65 08:09:38 INFO - PROCESS | 18275 | Values: 185.0 185.0 181.0 183.0 189.0 182.0 188.0 184.0 184.0 186.0 08:09:38 INFO - PROCESS | 18275 | 08:09:38 INFO - PROCESS | 18275 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.00 stddev:1.84 (2.6%) stddev-sans-first:1.45 08:09:38 INFO - PROCESS | 18275 | Values: 74.0 70.0 72.0 69.0 69.0 70.0 69.0 70.0 69.0 73.0 08:09:38 INFO - PROCESS | 18275 | -------- Summary: end -------- 08:09:38 INFO - PROCESS | 18275 | 08:09:39 INFO - TEST-INFO | 18275: exit 0 08:09:39 INFO - TEST-OK | kraken | took 64161ms 08:09:39 INFO - SUITE-END | took 1183s 08:09:39 INFO - Completed test suite (00:19:43) 08:09:39 INFO - javascript benchmark 08:09:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6243.895493830313, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2569.794416446684, 2579.871006449677, 2584.52186345526, 2619.515389652915, 2632.302439853438, 4761.642876784246, 4784.234522476405, 4838.9032219355595, 4842.152623290927, 4892.290617860417, 4774.331593576893, 4789.952100479003, 4802.639802014845, 4808.2547205183255, 4825.927611085828, 4414.97792511037, 4443.0, 4520.932186017212, 4521.819127234907, 4586.518415566367, 4484.910301793962, 4535.614472769802, 4571.222892108333, 4591.449026116854, 4595.701279416844, 4416.6025057744955, 4464.1071785643, 4488.147252022138, 4506.977465112654, 4533.252013417815, 4490.820367185309, 4493.03399769048, 4526.592606665383, 4539.387182730322, 4545.20458919691, 4466.12910482456, 4471.0, 4481.08137831747, 4507.346434766973, 4511.255642818964, 4665.5567721066445, 4695.295705644146, 4837.4920633333695, 4844.20070688336, 4872.025594881038, 5058.038972595195, 5121.385433747937, 5137.974310128426, 5160.974195129038, 5219.5041471060185, 5170.069387510247, 5173.224016397533, 5176.430592634807, 5191.844244672666, 5215.582753379758, 4752.691075080109, 4846.248831431134, 4865.56209941107, 4870.439899411613, 4870.707757534559, 5194.194899790463, 5334.386545547234, 5372.5433338166695, 5384.165454354581, 5465.453454654503, 5166.5866730661455, 5166.638335316492, 5184.222366645034, 5188.481151884781, 5192.844214673566, 5072.1123803335, 5091.389033316026, 5252.868678282997, 5255.710935898563, 5296.25852380667, 5075.771590278447, 5089.541941225308, 5106.821261255838, 5120.18077107661, 5164.406093299243, 5088.592912566985, 5091.3126727892, 5096.51583099605, 5106.514881086291, 5120.02719483297, 4231.30183519722, 4316.5467625899455, 4318.52496225415, 4323.481182258086, 4373.650107991353, 3702.5927148013698, 3754.2866855297407, 3768.4158955361963, 3819.427085937132, 3893.357595996629, 4419.381286619876, 4433.069055498381, 4436.822527098944, 4438.201123797748, 4476.328550717353, 5195.870103247449, 5217.243499692523, 5220.269162317279, 5227.60792940531, 5238.423773384925, 4392.868213953587, 4401.823927042882, 4421.336799480104, 4479.820807167742, 4485.945802736354, 4641.048585040058, 4644.976775116171, 4678.368420263289, 4678.719276843401, 4689.460712018094, 4847.103285892122, 4849.80600775965, 4855.247436647325, 4855.6601037927, 4898.2652602109965, 5200.479952004769, 5230.424653288059, 5245.291885595396, 5246.921296180484, 5249.921251181159, 4699.553542413465, 4725.50382209863, 4746.0745154694505, 4829.179039563212, 4842.322074909586, 5196.090684130338, 5197.8440646780655, 5202.869928251824, 5217.739113044257, 5241.97781432617, 5253.106971814877, 5271.367435907716, 5286.841394758163, 5298.920516192337, 5334.253204551444, 4909.656324057424, 4935.851924442272, 5074.517920797518, 5147.279380886754, 5153.948460515347, 5089.134847075932, 5130.563902068366, 5162.148245539444, 5184.403793563638, 5190.325257716624, 4835.540623640749, 4868.586170175433, 4871.391076115486, 4917.237828136645, 4986.102501549756, 5049.899002020013, 5067.265246539305, 5071.290019397361, 5073.315102461121, 5146.974265128651, 4855.465898751205, 4891.779869905791, 4906.116898958222, 4936.111499930047, 4993.875153121201], "value": 4751.250385988823, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.82920721558276, 525.7423862307468, 529.896670149321, 532.6138549551575, 549.631746729691, 37586.248275034486, 39203.607963920316, 39233.019174520654, 42734.14531709364, 42770.14459710804, 40184.79907600458, 40975.38536921948, 41072.38391424131, 41102.58897411025, 41120.79439602805, 7620.65707043183, 7791.727289544867, 7800.80497987552, 9533.666321678742, 9870.753231169243, 9696.418214907128, 9782.21742260621, 9809.0, 9822.312438129335, 9826.754331141743, 9717.368371055858, 9831.213502919749, 9844.114029737322, 9844.70465886021, 9852.064053914903, 9747.75630609237, 9769.560369783341, 9771.804563908681, 9777.608895644165, 9791.853122203172, 9299.953500232523, 9402.0, 9441.055894410572, 9467.621295148254, 9469.66856160038, 34153.487697684555, 34186.82906585476, 34568.82715586431, 34603.4809477858, 34673.4798978013, 3250.772445928786, 3427.3133869512094, 3448.258624395767, 3474.6699063588917, 3508.6666766657127, 25378.365540861443, 25920.351991200183, 26378.472430551283, 26608.73391266082, 26885.731142688517, 3295.983520082408, 3350.363430948112, 3378.2398960233995, 3379.898603041913, 3407.2844702612483, 255388.0, 255448.0, 255853.0, 256030.0, 257516.99999999814, 47332.76333618379, 47479.52520474751, 47539.76230118897, 47599.762001190466, 47780.5221947783, 49496.50503494919, 49499.752501237264, 49534.75232623814, 49551.50448495541, 49651.50348496469, 10912.399818010086, 11117.332960022262, 11170.664880053611, 11223.775524489465, 11258.43707814606, 8425.0, 8604.827903441896, 8782.077881822444, 8945.686900958435, 9024.278057755364, 15547.844521554865, 15682.059076455449, 15717.842821571867, 15724.449644262397, 15736.763948540782, 6247.531435142382, 6313.463355614732, 6322.747090116344, 6401.423871851555, 6479.319671434526, 780.6565111350988, 789.795561768308, 813.7680760983092, 817.4114637461018, 831.3100126894662, 25121.87439062793, 25333.0883484875, 25512.872435637702, 25922.09272675485, 26348.20955371342, 929.1730359979489, 961.0581630002637, 966.4681479854369, 978.2663002747939, 981.65642025292, 13650.522231722041, 14088.577342679737, 14104.224267665171, 14173.362198701083, 14227.217503037433, 347876.0, 347967.0, 351888.0, 352900.0, 354347.0, 49752.0, 50217.0, 50250.246246306335, 50382.244266336034, 50399.74800125976, 1201.1449798741814, 1216.3370962825281, 1220.5972029230334, 1222.3216115056052, 1227.6869398303375, 25347.746522534537, 25798.35504112337, 26922.32694182661, 27445.31386715348, 27533.449331013668, 41966.79016604897, 42160.57839421689, 42191.1561768769, 42251.0, 42258.154836903705, 295035.0, 297741.0, 299201.0, 300153.0, 300964.0, 22857.657135142654, 22967.196148134735, 23206.419839504146, 24143.15498957528, 25196.24411267665, 36228.63771362253, 36337.27325453529, 36465.453018204214, 37060.0, 37329.44005839969, 31850.20374490656, 32069.51895721613, 32316.35367292688, 32441.0, 32504.674953250164, 31355.0, 31747.52378714275, 32229.35541289208, 32969.0, 33147.0, 31727.36545269128, 31794.68205317917, 32057.153141623156, 32087.518687219243, 32412.675873240965, 26712.866435667696, 28975.42049159047, 29213.0, 29324.560131597616, 29640.258993524472, 22877.16982641418, 22882.31353059411, 22991.425214369774, 23080.192193273157, 23149.652755209023], "value": 16885.849182214868, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.5835006442721, 377.51677852348996, 378.3889019233938, 378.867396411256, 396.14432825097794, 15651.686966260682, 17031.48905532833, 17062.402815901445, 17125.828741712594, 17367.0, 16187.514374568753, 16324.428644997428, 16430.178491075458, 16560.0, 16940.745888811678, 4987.276844857484, 5175.456577059392, 5203.817866374678, 5207.531322181022, 5224.425313215544, 5078.898422031557, 5110.412302585213, 5389.541888939445, 5399.947010332967, 5474.0, 5406.053940560386, 5424.647397919142, 5432.728363581825, 5440.591955603346, 5444.292242008533, 5377.274068000828, 6078.331383547807, 6163.630182189083, 6163.999999999977, 6165.229346331708, 5219.295395121686, 5474.39781624021, 5492.780288788444, 5499.092649712793, 5500.28496295479, 12365.505379784798, 12649.810252846215, 12655.303958282291, 12660.62018139448, 12666.936665316707, 2337.941551461227, 2385.4751954569965, 2424.903003879843, 2429.97570024301, 2433.330834020641, 9085.545722713838, 9147.22248608869, 9187.0, 9525.19035881951, 9636.566354514063, 1684.3010150787409, 1734.5576877896183, 1738.1830539646346, 1765.8145894681002, 1769.0977601423238, 59383.0, 60676.745716736266, 64493.35506644967, 70909.99999999949, 72996.27003729947, 9286.164245217962, 9296.0, 9572.186364158984, 9621.230301575857, 9765.804683906283, 12786.872131278755, 12864.16382935125, 12932.0, 12954.481820727065, 12970.481180752853, 6384.7126879290545, 6393.648349340738, 6630.602163870183, 6690.799276021728, 6692.899606505906, 6025.307089684742, 6100.908486372708, 6237.812865614039, 6240.095186197976, 6256.468200203034, 3878.650921417086, 3952.4268980998168, 4135.234981528367, 4139.627433530996, 4237.512686041083, 1591.0692245036782, 1593.7768712380275, 1810.6107186954869, 1851.8425933795509, 1881.1628825172668, 897.6678628907346, 909.0863681999548, 917.6971599372191, 936.5551363102472, 952.1335584617964, 15561.610959725871, 15728.05631662104, 15984.200789960456, 16038.0, 16096.0, 898.23201163006, 900.8153328567628, 903.2367649336301, 912.1608120528997, 914.7575892388522, 8882.289416846637, 8917.464952102895, 8920.776980575538, 8948.418352807046, 9193.540322983825, 349809.0, 353544.0, 357980.0, 358686.0, 360909.0, 71495.0, 71602.0, 71705.6414717923, 73877.0, 74374.25625743781, 845.5011543189547, 859.3554833874595, 859.6475445067492, 859.9871001934976, 863.7495126413395, 13899.583012509642, 13954.441822326993, 13961.301934903011, 14004.448554219716, 14581.562553123424, 34574.30851383009, 35701.0, 35757.28485430329, 36017.099572510895, 36205.63794362128, 45008.77495612501, 45409.31886021646, 46143.538564613926, 48143.0, 52090.73954630203, 7516.661750221362, 7632.542047477169, 7700.961495192488, 7775.57234352116, 7988.920110798817, 11621.6513504595, 12305.56930507428, 12414.0, 12430.192037517532, 12534.623961281177, 9857.309988301036, 9947.602095916081, 10019.799604008025, 10219.693409197736, 10430.113440357654, 9955.850662239927, 10026.94986525092, 10486.632967846088, 10493.580256789643, 10501.0, 9741.853872192065, 9816.460094694861, 10035.69892903214, 10338.844917326396, 10365.63720269787, 7547.320741133324, 7557.470977031555, 7628.237176282328, 7656.4640475168435, 7720.3437707795465, 7490.063742032246, 8543.40196186261, 9630.422174669542, 9741.26940479481, 9914.30599858032], "value": 7942.20352765706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.6558494791357, 1323.9470421183153, 1339.564641491515, 1340.9798853017212, 1348.5819395987248, 6394.136791533142, 6606.669666516679, 6856.451483881284, 7262.346388825004, 7268.200497945229, 6776.864462710742, 7062.470314726404, 7074.540154889942, 7226.638668066602, 7226.819329516765, 2112.598606264805, 2141.5609799990966, 2141.6787481877686, 2142.2180903970093, 2150.1721837092755, 2130.1266480742897, 2137.967930481036, 2184.290105715641, 2195.9780402195934, 2215.7451893032267, 2157.6439887418555, 2186.9562608747815, 2188.3872515695552, 2189.343197040889, 2192.177933275022, 2041.3263623004373, 2076.802703743157, 2080.5942841145984, 2085.2180432337873, 2087.123408168573, 2136.647453170225, 2138.2088627207877, 2147.7207962964712, 2150.2044243629957, 2157.8273738100916, 7220.92779072213, 7257.237990011025, 7287.854242915165, 7355.080614923135, 7366.373858222004, 4358.629516491071, 4510.368548403226, 4517.864464066083, 4522.140793249272, 4528.18492671319, 2883.8413887236193, 3021.6978302169828, 3093.257618171645, 3122.406742718876, 3123.7813353065294, 1113.6492005018392, 1175.048210949134, 1180.3331117918349, 1192.493190394079, 1661.6012157082334, 9055.773605659846, 9142.771430714285, 9194.632214711404, 9220.769480762967, 9232.492212928286, 3801.7528860624525, 3950.1902110067367, 4019.879403617896, 4028.113814960704, 4044.7977601120415, 7700.884486732703, 7732.38140948734, 7737.6518056686455, 7743.419243556756, 7747.767566973, 4307.763073030951, 4324.0, 4329.805158767863, 4335.154644844288, 4337.718048326912, 3837.9616203838164, 3916.921661566753, 3965.1673148638533, 4037.959620403817, 4045.554988951213, 3156.2898347871546, 3198.712115909579, 3266.3467306538787, 3268.7548433867555, 3287.506873968876, 981.788915383196, 1003.2575893838507, 1029.562435964712, 1030.1552726763982, 1045.0, 641.0864518061836, 647.9092926990131, 801.7594721583501, 806.7700705298962, 840.8906842110487, 4816.060868130681, 4842.201036828884, 4860.538248866423, 4972.90054198914, 5016.899662006739, 828.7513745876213, 829.8838162657232, 837.9664813407395, 852.86780549015, 859.3254295378098, 3271.2966712156526, 3418.213810823475, 3452.5856897172, 3494.3884820156372, 3587.9103022424647, 8168.714095006646, 8459.154084591615, 8534.487930724052, 8546.273566746771, 8574.571271436404, 8256.215659512336, 8388.245057944814, 8459.111793261742, 8501.61742721579, 8506.404551681446, 6665.833354166185, 6725.663716814238, 6796.72813087471, 6801.625910574966, 6833.863322733518, 4532.5694059064335, 4822.758862056813, 4877.926831097608, 4933.062718083553, 5054.368203974504, 7794.259545343183, 7844.882326765217, 7845.843083138191, 7918.208179182036, 7969.0, 7905.762827115196, 7977.641006154621, 7978.441509094308, 8132.796680083045, 8149.348052155932, 5764.798232061858, 5875.50058245054, 5904.940950590439, 5914.3198532167635, 5915.994280972331, 6724.7646332378135, 6778.559393639398, 6793.558418702769, 6823.624700641512, 6901.930980690129, 5268.6575372600655, 6028.2766068072115, 6055.061465472859, 6099.115628233792, 6173.783917562864, 5951.315598706205, 5987.2815262168815, 6013.218281623536, 6023.849403764941, 6153.046277826944, 5773.220615216892, 5827.737751801094, 5879.412058794086, 5889.0, 5910.911336329872, 4287.099709061132, 4307.39696442492, 4385.890352741207, 4433.911321773611, 4501.07727915783, 4359.498657654283, 4364.061726728769, 4398.890027749204, 4438.977805110953, 4453.710508816916], "value": 3873.994854108804, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.3639040702752, 362.8639260277396, 366.2345697492241, 366.5015578812816, 370.39070728651353, 4935.383077115361, 4985.7784842713545, 4991.755359016641, 5009.649324547283, 5032.924506132411, 4945.109880221558, 4953.331300274462, 4980.153373926432, 4985.501449855022, 5002.824336280971, 3533.2050288685095, 3820.656140947321, 3924.862629807957, 3970.9801450992704, 4012.9598704013024, 3974.304496713078, 3995.0, 3997.4203740457615, 4007.5792041835625, 4024.738392004525, 3995.240904228202, 3999.7200195986293, 4012.879613611596, 4014.799260037001, 4018.97990510047, 3638.854445822164, 4029.9597004030024, 4033.415154802537, 4033.6974726895455, 4033.8184781684895, 3347.2301370684636, 3357.6474470180524, 3366.1584603849037, 3371.4100032494216, 3397.6092749333898, 4754.9286760698615, 4763.666543341968, 4787.13831510328, 4817.879553011203, 4846.418429788448, 4386.670999675037, 4405.801738921757, 4412.470503539564, 4414.719731277925, 4415.51429342772, 4914.533119353656, 5110.565601923841, 5158.226266060084, 5184.7407629618365, 5213.635045546814, 3682.287736202652, 3691.6123807000145, 3693.519842420495, 3718.8140592970244, 3723.962760372389, 5212.592599997979, 5427.565794736412, 5448.945510544923, 5463.863403414946, 5477.534409575191, 3730.720195985312, 3736.3461394255896, 3756.887293381203, 3763.247350529905, 3767.9058023549633, 3813.466114743938, 3819.255245227154, 3828.8277027533827, 3836.731428800013, 3840.539135303781, 4272.636825869774, 4367.825286988485, 4398.164348773723, 4398.64810815134, 4433.1577000369825, 5196.714180720021, 5226.947730522646, 5229.241759944863, 5236.711980841091, 5246.23929530216, 1283.2685369339388, 1289.239348784203, 1291.4051146833654, 1294.5792617399384, 1302.9022823288292, 829.8838162657232, 882.4484697064335, 886.7472770260445, 891.2558014058334, 892.1034360467688, 4368.847090351823, 4421.204183247047, 4424.358468022118, 4424.955750442518, 4439.644828413784, 5188.92216616751, 5260.0531904257605, 5268.604854635918, 5273.419923808378, 5305.602079844027, 4219.198352313112, 4427.867163985021, 4428.955710442919, 4436.3567282743825, 4452.243118669834, 2747.763506422118, 2774.764145047653, 2776.0, 2783.930401739932, 2790.897595449786, 4809.278608208797, 4818.012307476959, 4826.493218212037, 4832.299316599074, 4856.587190088811, 5244.659097158748, 5387.542058925035, 5390.353157621111, 5414.0, 5417.972910135503, 4764.213904705824, 4775.904481910412, 4790.8323208687525, 4802.0, 4845.442774080955, 5152.27868098474, 5192.688438693691, 5192.9740351299515, 5199.142141546602, 5218.582513398995, 5032.6225533084435, 5081.390233172043, 5119.539241468286, 5160.200168973815, 5248.291480650063, 4798.3042458843975, 4966.826161084345, 4979.751012449436, 4989.2516122580455, 5050.595952323879, 3746.2507498499863, 3770.905727356838, 3774.5281839770028, 3780.4140358244517, 3788.677962373119, 5386.380566234933, 5396.9190462142315, 5397.676139431646, 5406.21609866575, 5426.18607208906, 5015.3730783652045, 5214.322138122172, 5231.764570594256, 5260.842174734764, 5270.815521456884, 5217.217417387422, 5226.895462090661, 5227.843164705065, 5242.501962313574, 5245.318108645852, 5143.4085080216255, 5186.196139598368, 5188.325517682677, 5205.089109405763, 5207.27098206259, 5042.899142017066, 5254.292354984722, 5274.393444753749, 5276.419593844751, 5302.28419163408, 5143.254228136824, 5147.485251474973, 5154.7938082476285, 5192.169252919514, 5192.3769147701005], "value": 3914.4547769285664, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.6311665467342, 669.34069941108, 726.7020521586147, 730.3062091013537, 773.3836282170265, 12372.443240054197, 13983.580492585214, 14704.0, 15109.320080596372, 15376.769348459782, 14216.715665686681, 14821.40714371424, 15057.39770409185, 15276.618084547892, 15346.616334591641, 806.1857523900858, 829.4318391901537, 830.8089139497919, 831.3349320543571, 833.3374966901328, 789.6051974012994, 824.9298809601161, 824.9793755156109, 825.781167990483, 826.7271800305884, 853.8335024670192, 855.3798496090347, 855.4268640011207, 855.6534603485599, 855.8373908957309, 845.8688903220011, 846.272205902923, 847.5423271433418, 849.7450764770575, 851.1233429567525, 621.2048577820398, 621.8880601491729, 622.2532960447481, 622.9345918678519, 623.4295619508144, 13527.864721352758, 13782.93108534461, 13785.035047546677, 13803.585892423243, 14182.290885455686, 13201.471941122343, 14000.509982150576, 14063.36714847824, 14251.429942802275, 14265.857341426557, 10263.332883362558, 10384.117350025257, 10466.319689220252, 10526.894731052744, 10716.035556799925, 3119.5788568543185, 3153.2905096353365, 3159.5892533970436, 3160.2573395252098, 3174.825384603846, 62093.379066209214, 62977.68511157461, 63031.369686303005, 64823.67588162076, 66636.6668166661, 10140.442275674908, 10172.3724204127, 10230.130438912776, 10278.17774578047, 10341.586336546605, 20708.689369659467, 20738.688919666216, 20763.79236207649, 20766.896165519378, 20824.27115050966, 6287.371262873676, 6367.395097465734, 6470.838229044218, 6560.376764207393, 6561.40947314744, 3365.7139143173104, 3415.590129184514, 3442.6901578858024, 3482.7736197147105, 3499.842507087187, 3077.569140320357, 3127.46833038384, 3159.6524382317925, 3171.223050352632, 3180.9363812724077, 532.1618450939785, 552.9972350138304, 555.2088274209235, 556.1407625219047, 561.3432284227464, 524.7376311844077, 527.2197148220655, 528.1417696243604, 535.130413078747, 541.2152379050393, 7817.5309481431295, 8379.413441059067, 9044.728658140266, 9397.57710903011, 9658.565364558612, 481.9036192761462, 491.87211325055745, 497.62926619668576, 537.6263496869716, 550.243415303957, 3634.8546058157376, 3945.1320709443876, 4047.372657238133, 4061.3501839705505, 4067.074899641329, 61115.0, 61866.0, 61992.69003654953, 62690.0, 63779.68110159419, 55084.44915550874, 55726.72136639291, 56612.0, 56726.7163664179, 57637.71181144068, 47872.281915771295, 49301.26048109281, 50187.74906125446, 51418.228726569134, 51834.48165518372, 7294.927050729425, 7686.615669216629, 7729.6521656524465, 7806.336461400844, 7906.920930790849, 34962.0, 35206.82396588001, 35559.288814224084, 35908.820455897556, 36167.81916090403, 41312.0, 42908.35637465503, 42998.78500607602, 43060.0, 45270.77364613156, 9235.445873247627, 9630.0, 9631.9036809631, 9664.613415463302, 9752.85370719454, 10695.786084278116, 10740.892591074302, 10765.3540787553, 10820.4048777318, 10853.294535855144, 10070.899291006996, 10085.848712269175, 10218.386896786216, 10292.588296468057, 10417.583296668352, 9349.205317548085, 9417.529123543933, 9479.668211612561, 9746.805063898824, 9754.658586949423, 10202.0, 10215.284930054824, 10313.74215644615, 10385.273030888067, 10407.323523970917, 8151.429399941947, 8234.876476852733, 8348.290395316224, 8441.240288374076, 8535.3598480113, 7784.146847310618, 8017.358611311198, 8136.389770767098, 8295.336373090258, 9999.300118979645], "value": 6131.733289175738, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 115.0, 115.0, 115.0, 115.0, 115.0, 119.0, 116.0, 117.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 127.0, 185.0, 132.0, 128.0, 129.0, 128.0, 148.0, 128.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 173.0, 164.0, 164.0, 165.0, 162.0, 164.0, 162.0, 167.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 120.0, 78.0, 78.0, 77.0, 76.0, 98.0, 78.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 110.0, 86.0, 86.0, 85.0, 98.0, 85.0, 85.0, 103.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 118.0, 124.0, 118.0, 118.0, 118.0, 117.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 152.0, 150.0, 150.0, 151.0, 150.0, 150.0, 152.0, 152.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 95.0, 96.0, 96.0, 99.0, 96.0, 96.0, 96.0, 95.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 70.0, 67.0, 67.0, 73.0, 66.0, 66.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 68.0, 68.0, 68.0, 69.0, 67.0, 68.0, 68.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 97.0, 87.0, 86.0, 85.0, 86.0, 89.0, 86.0, 95.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 143.0, 137.0, 142.0, 134.0, 145.0, 145.0, 144.0, 216.0, 132.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 181.0, 183.0, 189.0, 182.0, 188.0, 184.0, 184.0, 186.0], "value": 184.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 72.0, 69.0, 69.0, 70.0, 69.0, 70.0, 69.0, 73.0], "value": 70.5, "unit": "score"}]}]} 08:09:39 INFO - Return code: 0 08:09:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:09:39 ERROR - # TBPL SUCCESS # 08:09:39 INFO - Running post-action listener: _resource_record_post_action 08:09:39 INFO - [mozharness: 2016-12-26 16:09:39.531668Z] Finished run-tests step (success) 08:09:39 INFO - Running post-run listener: _resource_record_post_run 08:09:39 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 383455232; Read time: 12; Write time: 261404 08:09:39 INFO - TinderboxPrint: CPU usage
13.5% 08:09:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:09:39 INFO - TinderboxPrint: I/O write bytes / time
383,455,232 / 261,404 08:09:39 INFO - TinderboxPrint: CPU idle
8,275.7 (86.5%) 08:09:39 INFO - TinderboxPrint: CPU user
1,233.4 (12.9%) 08:09:39 INFO - TinderboxPrint: Swap in / out
0 / 0 08:09:39 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99287040; Read time: 0; Write time: 64396 08:09:39 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 284168192; Read time: 0; Write time: 197008 08:09:40 INFO - Running post-run listener: _upload_blobber_files 08:09:40 INFO - Blob upload gear active. 08:09:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:09:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:09:40 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:09:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:09:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:41 INFO - (blobuploader) - INFO - Done attempting. 08:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:42 INFO - (blobuploader) - INFO - Done attempting. 08:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:09:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:43 INFO - (blobuploader) - INFO - Done attempting. 08:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:46 INFO - (blobuploader) - INFO - Done attempting. 08:09:46 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:46 INFO - Return code: 0 08:09:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b8ce5a46a87d2b97c2523aac4f5ddc3cf5f717a7145a7dd3e24aebcd148d303c5db0e0759e0253e167d64d0815e9ca13bb41456557aa242f426003f5b90a4f2", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb744cc274d09236bc2a3b1bf8e85cefee4669dc6e0f89f5f7b9262989ab61d4c341519cd78c473949e36ef1266e540a17298a238ef7551079602148bdeff1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/359900c3de6d218a4c1977a42bf8434a845e76adb128a909b396266cdff48328013f736180557b498703d31ae548d220871aa4c9400c5da3af4993d94ee29aa0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a0aba84077e32e647ec0217d65366f07baff9c373fd11ebae9ae0a5fc1ebd0df38f36fc3e5eca36b425ed01cae0adc4399460f72c0972378bc87e2e0d198ade"} 08:09:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:46 INFO - Contents: 08:09:46 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b8ce5a46a87d2b97c2523aac4f5ddc3cf5f717a7145a7dd3e24aebcd148d303c5db0e0759e0253e167d64d0815e9ca13bb41456557aa242f426003f5b90a4f2", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb744cc274d09236bc2a3b1bf8e85cefee4669dc6e0f89f5f7b9262989ab61d4c341519cd78c473949e36ef1266e540a17298a238ef7551079602148bdeff1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/359900c3de6d218a4c1977a42bf8434a845e76adb128a909b396266cdff48328013f736180557b498703d31ae548d220871aa4c9400c5da3af4993d94ee29aa0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a0aba84077e32e647ec0217d65366f07baff9c373fd11ebae9ae0a5fc1ebd0df38f36fc3e5eca36b425ed01cae0adc4399460f72c0972378bc87e2e0d198ade"} 08:09:46 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:46 INFO - Copying logs to upload dir... 08:09:46 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.090654 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-26 08:09:46.561529) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:46.565248) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b8ce5a46a87d2b97c2523aac4f5ddc3cf5f717a7145a7dd3e24aebcd148d303c5db0e0759e0253e167d64d0815e9ca13bb41456557aa242f426003f5b90a4f2", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb744cc274d09236bc2a3b1bf8e85cefee4669dc6e0f89f5f7b9262989ab61d4c341519cd78c473949e36ef1266e540a17298a238ef7551079602148bdeff1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/359900c3de6d218a4c1977a42bf8434a845e76adb128a909b396266cdff48328013f736180557b498703d31ae548d220871aa4c9400c5da3af4993d94ee29aa0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a0aba84077e32e647ec0217d65366f07baff9c373fd11ebae9ae0a5fc1ebd0df38f36fc3e5eca36b425ed01cae0adc4399460f72c0972378bc87e2e0d198ade"} build_url:https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011993 build_url: 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b8ce5a46a87d2b97c2523aac4f5ddc3cf5f717a7145a7dd3e24aebcd148d303c5db0e0759e0253e167d64d0815e9ca13bb41456557aa242f426003f5b90a4f2", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb744cc274d09236bc2a3b1bf8e85cefee4669dc6e0f89f5f7b9262989ab61d4c341519cd78c473949e36ef1266e540a17298a238ef7551079602148bdeff1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/359900c3de6d218a4c1977a42bf8434a845e76adb128a909b396266cdff48328013f736180557b498703d31ae548d220871aa4c9400c5da3af4993d94ee29aa0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a0aba84077e32e647ec0217d65366f07baff9c373fd11ebae9ae0a5fc1ebd0df38f36fc3e5eca36b425ed01cae0adc4399460f72c0972378bc87e2e0d198ade"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:46.591776) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:46.592168) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=00204705a9daa57ed339a427000001b9-1482766512.992222-1122327221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004561 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:46.676025) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:09:46.676406) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:09:46.676816) ========= ========= Total master_lag: 0.18 =========