builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1462802953.27 results: success (0) buildid: 20160509043053 builduid: 64a8f71f12304e64bd24db5173202d98 revision: 043082cb7bd8490c60815f67fbd1f33323ad7663 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.268148) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.268791) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.269239) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.282078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.282605) ========= 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=33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-09 07:09:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.0M=0s 2016-05-09 07:09:13 (32.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.069626 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.365161) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.365745) ========= 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=33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019031 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:13.417728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 07:09:13.418195) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 043082cb7bd8490c60815f67fbd1f33323ad7663 --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 043082cb7bd8490c60815f67fbd1f33323ad7663 --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=33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-09 07:09:13,765 truncating revision to first 12 chars 2016-05-09 07:09:13,765 Setting DEBUG logging. 2016-05-09 07:09:13,765 attempt 1/10 2016-05-09 07:09:13,765 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/043082cb7bd8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 07:09:14,662 unpacking tar archive at: mozilla-central-043082cb7bd8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.159422 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 07:09:14.591238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:14.591665) ========= script_repo_revision: 043082cb7bd8490c60815f67fbd1f33323ad7663 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:14.592122) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:14.592408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 07:09:14.601186) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-09 07:09:14.601475) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:09:15 INFO - MultiFileLogger online at 20160509 07:09:15 in /builds/slave/test 07:09:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:09:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:09:15 INFO - {'append_to_log': False, 07:09:15 INFO - 'base_work_dir': '/builds/slave/test', 07:09:15 INFO - 'blob_upload_branch': 'Firefox', 07:09:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:09:15 INFO - 'branch': 'Firefox', 07:09:15 INFO - 'buildbot_json_path': 'buildprops.json', 07:09:15 INFO - 'config_files': ('talos/linux_config.py',), 07:09:15 INFO - 'default_actions': ('clobber', 07:09:15 INFO - 'read-buildbot-config', 07:09:15 INFO - 'download-and-extract', 07:09:15 INFO - 'populate-webroot', 07:09:15 INFO - 'create-virtualenv', 07:09:15 INFO - 'install', 07:09:15 INFO - 'run-tests'), 07:09:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:09:15 INFO - 'download_minidump_stackwalk': True, 07:09:15 INFO - 'download_symbols': 'ondemand', 07:09:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:09:15 INFO - 'tooltool.py': '/tools/tooltool.py', 07:09:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:09:15 INFO - '/tools/misc-python/virtualenv.py')}, 07:09:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:09:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:09:15 INFO - 'installer_path': 'installer.exe', 07:09:15 INFO - 'log_level': 'info', 07:09:15 INFO - 'log_name': 'talos', 07:09:15 INFO - 'log_to_console': True, 07:09:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:09:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:09:15 INFO - 'opt_config_files': (), 07:09:15 INFO - 'pip_index': False, 07:09:15 INFO - 'sps_profile': False, 07:09:15 INFO - 'sps_profile_interval': 0, 07:09:15 INFO - 'suite': 'dromaeojs', 07:09:15 INFO - 'system_bits': '32', 07:09:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:09:15 INFO - 'title': 'talos-linux64-ix-063', 07:09:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:09:15 INFO - 'use_talos_json': True, 07:09:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:09:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:09:15 INFO - 'work_dir': 'build'} 07:09:15 INFO - ##### 07:09:15 INFO - ##### Running clobber step. 07:09:15 INFO - ##### 07:09:15 INFO - Running pre-action listener: _resource_record_pre_action 07:09:15 INFO - Running main action method: clobber 07:09:15 INFO - rmtree: /builds/slave/test/build 07:09:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:09:16 INFO - Running post-action listener: _resource_record_post_action 07:09:16 INFO - ##### 07:09:16 INFO - ##### Finished clobber step (success) 07:09:16 INFO - ##### 07:09:16 INFO - ##### 07:09:16 INFO - ##### Running read-buildbot-config step. 07:09:16 INFO - ##### 07:09:16 INFO - Running pre-action listener: _resource_record_pre_action 07:09:16 INFO - Running main action method: read_buildbot_config 07:09:16 INFO - Using buildbot properties: 07:09:16 INFO - { 07:09:16 INFO - "project": "", 07:09:16 INFO - "product": "firefox", 07:09:16 INFO - "script_repo_revision": "production", 07:09:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:09:16 INFO - "repository": "", 07:09:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:09:16 INFO - "buildid": "20160509043053", 07:09:16 INFO - "pgo_build": "True", 07:09:16 INFO - "basedir": "/builds/slave/test", 07:09:16 INFO - "buildnumber": 279, 07:09:16 INFO - "slavename": "talos-linux64-ix-063", 07:09:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:09:16 INFO - "platform": "ubuntu64_hw", 07:09:16 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:09:16 INFO - "branch": "mozilla-central", 07:09:16 INFO - "repo_path": "mozilla-central", 07:09:16 INFO - "revision": "043082cb7bd8490c60815f67fbd1f33323ad7663", 07:09:16 INFO - "stage_platform": "linux64-pgo", 07:09:16 INFO - "builduid": "64a8f71f12304e64bd24db5173202d98", 07:09:16 INFO - "slavebuilddir": "test" 07:09:16 INFO - } 07:09:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:09:16 INFO - Running post-action listener: _resource_record_post_action 07:09:16 INFO - ##### 07:09:16 INFO - ##### Finished read-buildbot-config step (success) 07:09:16 INFO - ##### 07:09:16 INFO - ##### 07:09:16 INFO - ##### Running download-and-extract step. 07:09:16 INFO - ##### 07:09:16 INFO - Running pre-action listener: _resource_record_pre_action 07:09:16 INFO - Running main action method: download_and_extract 07:09:16 INFO - mkdir: /builds/slave/test/build/tests 07:09:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:16 INFO - https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:09:19 INFO - Downloaded 1361 bytes. 07:09:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:09:19 INFO - Using the following test package requirements: 07:09:19 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:09:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:09:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:09:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'jsshell-linux-x86_64.zip'], 07:09:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:09:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:09:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:09:19 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:09:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:09:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:09:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:09: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:19 INFO - https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:09:25 INFO - Downloaded 23895527 bytes. 07:09:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:09:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:09:27 INFO - Return code: 0 07:09:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:09:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:27 INFO - https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:09:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:09:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:09:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:09:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:09:36 INFO - Downloaded 23895527 bytes. 07:09:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:09:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:09:37 INFO - Return code: 0 07:09:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:37 INFO - https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:09:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:09:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:09:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:09:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:09:38 INFO - Downloaded 10840152 bytes. 07:09:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:09:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:09:39 INFO - Return code: 0 07:09: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:39 INFO - https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:09:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:09:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:09:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:09:40 INFO - Downloaded 58438153 bytes. 07:09:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:09:40 INFO - mkdir: /builds/slave/test/properties 07:09:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:09:40 INFO - Writing to file /builds/slave/test/properties/build_url 07:09:40 INFO - Contents: 07:09:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:09:41 INFO - Running post-action listener: _resource_record_post_action 07:09:41 INFO - Running post-action listener: set_extra_try_arguments 07:09:41 INFO - ##### 07:09:41 INFO - ##### Finished download-and-extract step (success) 07:09:41 INFO - ##### 07:09:41 INFO - ##### 07:09:41 INFO - ##### Running populate-webroot step. 07:09:41 INFO - ##### 07:09:41 INFO - Running pre-action listener: _resource_record_pre_action 07:09:41 INFO - Running main action method: populate_webroot 07:09:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:09:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:09:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:09:41 INFO - '--tppagecycles', 07:09:41 INFO - '7'], 07:09:41 INFO - 'tests': ['tsvgm']}}, 07:09:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:09:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:09:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:09:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:09:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:09:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:09:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['damp', 'tps']}, 07:09:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'tests': ['damp', 'tps']}, 07:09:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['dromaeo_dom']}, 07:09:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:09:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['a11yr', 07:09:41 INFO - 'ts_paint', 07:09:41 INFO - 'tpaint', 07:09:41 INFO - 'sessionrestore', 07:09:41 INFO - 'sessionrestore_no_auto_restore', 07:09:41 INFO - 'tabpaint']}, 07:09:41 INFO - 'other-e10s': {'tests': ['a11yr', 07:09:41 INFO - 'ts_paint', 07:09:41 INFO - 'tpaint', 07:09:41 INFO - 'sessionrestore', 07:09:41 INFO - 'sessionrestore_no_auto_restore', 07:09:41 INFO - 'tabpaint']}, 07:09:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:09:41 INFO - 'ts_paint', 07:09:41 INFO - 'tpaint', 07:09:41 INFO - 'sessionrestore', 07:09:41 INFO - 'sessionrestore_no_auto_restore', 07:09:41 INFO - 'tabpaint']}, 07:09:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:09:41 INFO - 'ts_paint', 07:09:41 INFO - 'tpaint', 07:09:41 INFO - 'sessionrestore', 07:09:41 INFO - 'sessionrestore_no_auto_restore', 07:09:41 INFO - 'tabpaint']}, 07:09:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['a11yr', 07:09:41 INFO - 'ts_paint', 07:09:41 INFO - 'tpaint', 07:09:41 INFO - 'sessionrestore', 07:09:41 INFO - 'sessionrestore_no_auto_restore', 07:09:41 INFO - 'tabpaint']}, 07:09:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['a11yr', 07:09:41 INFO - 'ts_paint', 07:09:41 INFO - 'tpaint', 07:09:41 INFO - 'sessionrestore', 07:09:41 INFO - 'sessionrestore_no_auto_restore', 07:09:41 INFO - 'tabpaint']}, 07:09:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['tsvgx', 07:09:41 INFO - 'tsvgr_opacity', 07:09:41 INFO - 'tart', 07:09:41 INFO - 'tscrollx', 07:09:41 INFO - 'cart']}, 07:09:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:09:41 INFO - 'tsvgr_opacity', 07:09:41 INFO - 'tart', 07:09:41 INFO - 'tscrollx', 07:09:41 INFO - 'cart']}, 07:09:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'talos_options': ['--disable-e10s'], 07:09:41 INFO - 'tests': ['tp5o']}, 07:09:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'tests': ['tp5o']}, 07:09:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'talos_options': ['--disable-e10s', 07:09:41 INFO - '--xperf_path', 07:09:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:09:41 INFO - 'tests': ['tp5n']}, 07:09:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:09:41 INFO - 'talos_options': ['--xperf_path', 07:09:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:09:41 INFO - 'tests': ['tp5n']}}, 07:09:41 INFO - 'talos.zip': {'path': '', 07:09:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:09:41 INFO - Running post-action listener: _resource_record_post_action 07:09:41 INFO - ##### 07:09:41 INFO - ##### Finished populate-webroot step (success) 07:09:41 INFO - ##### 07:09:41 INFO - ##### 07:09:41 INFO - ##### Running create-virtualenv step. 07:09:41 INFO - ##### 07:09:41 INFO - Running pre-action listener: _resource_record_pre_action 07:09:41 INFO - Running main action method: create_virtualenv 07:09:41 INFO - Creating virtualenv /builds/slave/test/build/venv 07:09:41 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:09:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:09:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:09:41 INFO - Using real prefix '/usr' 07:09:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:09:41 INFO - Installing distribute.............................................................................................................................................................................................done. 07:09:43 INFO - Installing pip.................done. 07:09:43 INFO - Return code: 0 07:09:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:09:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:43 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 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:43 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:09:43 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:09:43 INFO - Using env: {'DISPLAY': ':0', 07:09:43 INFO - 'HOME': '/home/cltbld', 07:09:43 INFO - 'LANG': 'en_US.UTF-8', 07:09:43 INFO - 'LANGUAGE': 'en_US:en', 07:09:43 INFO - 'LOGNAME': 'cltbld', 07:09:43 INFO - 'MAIL': '/var/mail/cltbld', 07:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:43 INFO - 'MOZ_NO_REMOTE': '1', 07:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:43 INFO - 'NO_EM_RESTART': '1', 07:09:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:43 INFO - 'PWD': '/builds/slave/test', 07:09:43 INFO - 'SHELL': '/bin/bash', 07:09:43 INFO - 'SHLVL': '1', 07:09:43 INFO - 'TERM': 'linux', 07:09:43 INFO - 'TMOUT': '86400', 07:09:43 INFO - 'USER': 'cltbld', 07:09:43 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:43 INFO - '_': '/tools/buildbot/bin/python'} 07:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:44 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:09:44 INFO - Cleaning up... 07:09:44 INFO - Return code: 0 07:09:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:09:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:44 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:44 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>=0.7.1'] in /builds/slave/test/build 07:09:44 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>=0.7.1 07:09:44 INFO - Using env: {'DISPLAY': ':0', 07:09:44 INFO - 'HOME': '/home/cltbld', 07:09:44 INFO - 'LANG': 'en_US.UTF-8', 07:09:44 INFO - 'LANGUAGE': 'en_US:en', 07:09:44 INFO - 'LOGNAME': 'cltbld', 07:09:44 INFO - 'MAIL': '/var/mail/cltbld', 07:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:44 INFO - 'MOZ_NO_REMOTE': '1', 07:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:44 INFO - 'NO_EM_RESTART': '1', 07:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:44 INFO - 'PWD': '/builds/slave/test', 07:09:44 INFO - 'SHELL': '/bin/bash', 07:09:44 INFO - 'SHLVL': '1', 07:09:44 INFO - 'TERM': 'linux', 07:09:44 INFO - 'TMOUT': '86400', 07:09:44 INFO - 'USER': 'cltbld', 07:09:44 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:44 INFO - '_': '/tools/buildbot/bin/python'} 07:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:44 INFO - Downloading/unpacking psutil>=0.7.1 07:09:44 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:09:44 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:09:44 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:09:44 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:09:44 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:09:44 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:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:09:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:09:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:09:46 INFO - Installing collected packages: psutil 07:09:46 INFO - Running setup.py install for psutil 07:09:46 INFO - building 'psutil._psutil_linux' extension 07:09:46 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:09:46 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:09:46 INFO - building 'psutil._psutil_posix' extension 07:09:46 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:09:46 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:09:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:09:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:09:46 INFO - Successfully installed psutil 07:09:46 INFO - Cleaning up... 07:09:46 INFO - Return code: 0 07:09:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:09:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:46 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:46 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.0.0'] in /builds/slave/test/build 07:09:46 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.0.0 07:09:46 INFO - Using env: {'DISPLAY': ':0', 07:09:46 INFO - 'HOME': '/home/cltbld', 07:09:46 INFO - 'LANG': 'en_US.UTF-8', 07:09:46 INFO - 'LANGUAGE': 'en_US:en', 07:09:46 INFO - 'LOGNAME': 'cltbld', 07:09:46 INFO - 'MAIL': '/var/mail/cltbld', 07:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:46 INFO - 'MOZ_NO_REMOTE': '1', 07:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:46 INFO - 'NO_EM_RESTART': '1', 07:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:46 INFO - 'PWD': '/builds/slave/test', 07:09:46 INFO - 'SHELL': '/bin/bash', 07:09:46 INFO - 'SHLVL': '1', 07:09:46 INFO - 'TERM': 'linux', 07:09:46 INFO - 'TMOUT': '86400', 07:09:46 INFO - 'USER': 'cltbld', 07:09:46 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:46 INFO - '_': '/tools/buildbot/bin/python'} 07:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:09:47 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:09:47 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:09:47 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:09:47 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:09:47 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:09:47 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:09:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:09:49 INFO - Installing collected packages: mozsystemmonitor 07:09:49 INFO - Running setup.py install for mozsystemmonitor 07:09:49 INFO - Successfully installed mozsystemmonitor 07:09:49 INFO - Cleaning up... 07:09:49 INFO - Return code: 0 07:09:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:09:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:49 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 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:49 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:09:49 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:09:49 INFO - Using env: {'DISPLAY': ':0', 07:09:49 INFO - 'HOME': '/home/cltbld', 07:09:49 INFO - 'LANG': 'en_US.UTF-8', 07:09:49 INFO - 'LANGUAGE': 'en_US:en', 07:09:49 INFO - 'LOGNAME': 'cltbld', 07:09:49 INFO - 'MAIL': '/var/mail/cltbld', 07:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:49 INFO - 'MOZ_NO_REMOTE': '1', 07:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:49 INFO - 'NO_EM_RESTART': '1', 07:09:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:49 INFO - 'PWD': '/builds/slave/test', 07:09:49 INFO - 'SHELL': '/bin/bash', 07:09:49 INFO - 'SHLVL': '1', 07:09:49 INFO - 'TERM': 'linux', 07:09:49 INFO - 'TMOUT': '86400', 07:09:49 INFO - 'USER': 'cltbld', 07:09:49 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:49 INFO - '_': '/tools/buildbot/bin/python'} 07:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:49 INFO - Downloading/unpacking blobuploader==1.2.4 07:09:49 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:09:49 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:09:49 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:09:49 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:09:49 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:09:49 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:09:51 INFO - Downloading blobuploader-1.2.4.tar.gz 07:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:09:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:09:51 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:09:51 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:09:51 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:09:51 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:09:51 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:09:51 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:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:09:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:09:51 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:09:51 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:09:51 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:09:51 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:09:51 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:09:51 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:09:51 INFO - Downloading docopt-0.6.1.tar.gz 07:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:09:52 INFO - Installing collected packages: blobuploader, requests, docopt 07:09:52 INFO - Running setup.py install for blobuploader 07:09:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:09:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:09:52 INFO - Running setup.py install for requests 07:09:52 INFO - Running setup.py install for docopt 07:09:52 INFO - Successfully installed blobuploader requests docopt 07:09:52 INFO - Cleaning up... 07:09:52 INFO - Return code: 0 07:09:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:52 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 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:52 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:09:52 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:09:52 INFO - Using env: {'DISPLAY': ':0', 07:09:52 INFO - 'HOME': '/home/cltbld', 07:09:52 INFO - 'LANG': 'en_US.UTF-8', 07:09:52 INFO - 'LANGUAGE': 'en_US:en', 07:09:52 INFO - 'LOGNAME': 'cltbld', 07:09:52 INFO - 'MAIL': '/var/mail/cltbld', 07:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:52 INFO - 'MOZ_NO_REMOTE': '1', 07:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:52 INFO - 'NO_EM_RESTART': '1', 07:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:52 INFO - 'PWD': '/builds/slave/test', 07:09:52 INFO - 'SHELL': '/bin/bash', 07:09:52 INFO - 'SHLVL': '1', 07:09:52 INFO - 'TERM': 'linux', 07:09:52 INFO - 'TMOUT': '86400', 07:09:52 INFO - 'USER': 'cltbld', 07:09:52 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:52 INFO - '_': '/tools/buildbot/bin/python'} 07:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:52 INFO - Running setup.py (path:/tmp/pip-h0rG8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:53 INFO - Running setup.py (path:/tmp/pip-gW0b1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:53 INFO - Running setup.py (path:/tmp/pip-i6IGVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:53 INFO - Running setup.py (path:/tmp/pip-KmKeUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:53 INFO - Running setup.py (path:/tmp/pip-sWbVwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:53 INFO - Running setup.py (path:/tmp/pip-wIZDgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:53 INFO - Running setup.py (path:/tmp/pip-OieVCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:53 INFO - Running setup.py (path:/tmp/pip-WzTOXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:53 INFO - Running setup.py (path:/tmp/pip-xajIaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:53 INFO - Running setup.py (path:/tmp/pip-QzyBh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:53 INFO - Running setup.py (path:/tmp/pip-iWfxwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:53 INFO - Running setup.py (path:/tmp/pip-50ZCqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:53 INFO - Running setup.py (path:/tmp/pip-AOBCVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:53 INFO - Running setup.py (path:/tmp/pip-xRpXN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:53 INFO - Running setup.py (path:/tmp/pip-YsA7xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:54 INFO - Running setup.py (path:/tmp/pip-JjNMXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:54 INFO - Running setup.py (path:/tmp/pip-vIs0qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:09:54 INFO - Running setup.py install for manifestparser 07:09:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Running setup.py install for mozcrash 07:09:54 INFO - Running setup.py install for mozdebug 07:09:54 INFO - Running setup.py install for mozdevice 07:09:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Running setup.py install for mozfile 07:09:54 INFO - Running setup.py install for mozhttpd 07:09:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Running setup.py install for mozinfo 07:09:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Running setup.py install for mozInstall 07:09:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:09:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Running setup.py install for mozleak 07:09:55 INFO - Running setup.py install for mozlog 07:09:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Running setup.py install for moznetwork 07:09:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Running setup.py install for mozprocess 07:09:55 INFO - Running setup.py install for mozprofile 07:09:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Running setup.py install for mozrunner 07:09:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Running setup.py install for mozscreenshot 07:09:55 INFO - Running setup.py install for moztest 07:09:55 INFO - Running setup.py install for mozversion 07:09:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:09:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:09:55 INFO - Cleaning up... 07:09:56 INFO - Return code: 0 07:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:56 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 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:56 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:09:56 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:09:56 INFO - Using env: {'DISPLAY': ':0', 07:09:56 INFO - 'HOME': '/home/cltbld', 07:09:56 INFO - 'LANG': 'en_US.UTF-8', 07:09:56 INFO - 'LANGUAGE': 'en_US:en', 07:09:56 INFO - 'LOGNAME': 'cltbld', 07:09:56 INFO - 'MAIL': '/var/mail/cltbld', 07:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:56 INFO - 'MOZ_NO_REMOTE': '1', 07:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:56 INFO - 'NO_EM_RESTART': '1', 07:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:56 INFO - 'PWD': '/builds/slave/test', 07:09:56 INFO - 'SHELL': '/bin/bash', 07:09:56 INFO - 'SHLVL': '1', 07:09:56 INFO - 'TERM': 'linux', 07:09:56 INFO - 'TMOUT': '86400', 07:09:56 INFO - 'USER': 'cltbld', 07:09:56 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:56 INFO - '_': '/tools/buildbot/bin/python'} 07:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:09:56 INFO - Running setup.py (path:/tmp/pip-7lkMK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:09:56 INFO - Running setup.py (path:/tmp/pip-jw3hJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:09:56 INFO - Running setup.py (path:/tmp/pip-Rf2fVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:09:56 INFO - Running setup.py (path:/tmp/pip-eoDzOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:09:56 INFO - Running setup.py (path:/tmp/pip-z7VlGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:09:56 INFO - Running setup.py (path:/tmp/pip-rmnmPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:09:56 INFO - Running setup.py (path:/tmp/pip-K4Yn3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:09:56 INFO - Running setup.py (path:/tmp/pip-yh52l6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:09:56 INFO - Running setup.py (path:/tmp/pip-4TkZSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:09:56 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:09:56 INFO - Running setup.py (path:/tmp/pip-j1mqRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:09:56 INFO - Running setup.py (path:/tmp/pip-RdtTGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:09:57 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:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:09:57 INFO - Running setup.py (path:/tmp/pip-Qj1miO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:09:57 INFO - Running setup.py (path:/tmp/pip-vgCxL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:09:57 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:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:09:57 INFO - Running setup.py (path:/tmp/pip-9Ffcqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:57 INFO - Running setup.py (path:/tmp/pip-9bhmCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:09:57 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:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:09:57 INFO - Running setup.py (path:/tmp/pip-HipldY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:09:57 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:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:09:57 INFO - Running setup.py (path:/tmp/pip-9sHlv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:09:57 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:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:09:57 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:09:57 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:09:57 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:09:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:09:57 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:09:57 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:09:57 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:09:57 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:09:57 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:09:57 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:09:59 INFO - Downloading blessings-1.6.tar.gz 07:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:09:59 INFO - Installing collected packages: blessings 07:09:59 INFO - Running setup.py install for blessings 07:09:59 INFO - Successfully installed blessings 07:09:59 INFO - Cleaning up... 07:09:59 INFO - Return code: 0 07:09:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:09:59 INFO - Reading from file tmpfile_stdout 07:09:59 INFO - Current package versions: 07:09:59 INFO - argparse == 1.2.1 07:09:59 INFO - blessings == 1.6 07:09:59 INFO - blobuploader == 1.2.4 07:09:59 INFO - docopt == 0.6.1 07:09:59 INFO - manifestparser == 1.1 07:09:59 INFO - mozInstall == 1.12 07:09:59 INFO - mozcrash == 0.17 07:09:59 INFO - mozdebug == 0.1 07:09:59 INFO - mozdevice == 0.48 07:09:59 INFO - mozfile == 1.2 07:09:59 INFO - mozhttpd == 0.7 07:09:59 INFO - mozinfo == 0.9 07:09:59 INFO - mozleak == 0.1 07:09:59 INFO - mozlog == 3.1 07:09:59 INFO - moznetwork == 0.27 07:09:59 INFO - mozprocess == 0.22 07:09:59 INFO - mozprofile == 0.28 07:09:59 INFO - mozrunner == 6.11 07:09:59 INFO - mozscreenshot == 0.1 07:09:59 INFO - mozsystemmonitor == 0.0 07:09:59 INFO - moztest == 0.7 07:09:59 INFO - mozversion == 1.4 07:09:59 INFO - psutil == 3.1.1 07:09:59 INFO - requests == 1.2.3 07:09:59 INFO - wsgiref == 0.1.2 07:09:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:09:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:59 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 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:59 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:09:59 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:09:59 INFO - Using env: {'DISPLAY': ':0', 07:09:59 INFO - 'HOME': '/home/cltbld', 07:09:59 INFO - 'LANG': 'en_US.UTF-8', 07:09:59 INFO - 'LANGUAGE': 'en_US:en', 07:09:59 INFO - 'LOGNAME': 'cltbld', 07:09:59 INFO - 'MAIL': '/var/mail/cltbld', 07:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:59 INFO - 'MOZ_NO_REMOTE': '1', 07:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:09:59 INFO - 'NO_EM_RESTART': '1', 07:09:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:59 INFO - 'PWD': '/builds/slave/test', 07:09:59 INFO - 'SHELL': '/bin/bash', 07:09:59 INFO - 'SHLVL': '1', 07:09:59 INFO - 'TERM': 'linux', 07:09:59 INFO - 'TMOUT': '86400', 07:09:59 INFO - 'USER': 'cltbld', 07:09:59 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:59 INFO - '_': '/tools/buildbot/bin/python'} 07:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 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:09:59 INFO - Cleaning up... 07:09:59 INFO - Return code: 0 07:09:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:09:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:09:59 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 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:09:59 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:09:59 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:10:00 INFO - Using env: {'DISPLAY': ':0', 07:10:00 INFO - 'HOME': '/home/cltbld', 07:10:00 INFO - 'LANG': 'en_US.UTF-8', 07:10:00 INFO - 'LANGUAGE': 'en_US:en', 07:10:00 INFO - 'LOGNAME': 'cltbld', 07:10:00 INFO - 'MAIL': '/var/mail/cltbld', 07:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:00 INFO - 'MOZ_NO_REMOTE': '1', 07:10:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:10:00 INFO - 'NO_EM_RESTART': '1', 07:10:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:00 INFO - 'PWD': '/builds/slave/test', 07:10:00 INFO - 'SHELL': '/bin/bash', 07:10:00 INFO - 'SHLVL': '1', 07:10:00 INFO - 'TERM': 'linux', 07:10:00 INFO - 'TMOUT': '86400', 07:10:00 INFO - 'USER': 'cltbld', 07:10:00 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:00 INFO - '_': '/tools/buildbot/bin/python'} 07:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:00 INFO - Downloading/unpacking jsonschema 07:10:00 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:10:00 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:10:00 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:10:00 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:10:00 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:10:00 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:10:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:10:02 INFO - Downloading/unpacking functools32 (from jsonschema) 07:10:02 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:10:02 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:10:02 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:10:02 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:10:02 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:10:02 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:10:02 INFO - Downloading functools32-3.2.3-2.tar.gz 07:10:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:10:02 INFO - warning: no files found matching '*.txt' 07:10:02 INFO - no previously-included directories found matching 'build' 07:10:02 INFO - no previously-included directories found matching 'dist' 07:10:02 INFO - no previously-included directories found matching '.git*' 07:10:02 INFO - Installing collected packages: jsonschema, functools32 07:10:02 INFO - Running setup.py install for functools32 07:10:02 INFO - warning: no files found matching '*.txt' 07:10:02 INFO - no previously-included directories found matching 'build' 07:10:02 INFO - no previously-included directories found matching 'dist' 07:10:02 INFO - no previously-included directories found matching '.git*' 07:10:02 INFO - Successfully installed jsonschema functools32 07:10:02 INFO - Cleaning up... 07:10:02 INFO - Return code: 0 07:10:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:10:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:02 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc7cbc8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, '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': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', '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:10:02 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', 'flake8'] in /builds/slave/test/build 07:10:02 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 flake8 07:10:02 INFO - Using env: {'DISPLAY': ':0', 07:10:02 INFO - 'HOME': '/home/cltbld', 07:10:02 INFO - 'LANG': 'en_US.UTF-8', 07:10:02 INFO - 'LANGUAGE': 'en_US:en', 07:10:02 INFO - 'LOGNAME': 'cltbld', 07:10:02 INFO - 'MAIL': '/var/mail/cltbld', 07:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:02 INFO - 'MOZ_NO_REMOTE': '1', 07:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:10:02 INFO - 'NO_EM_RESTART': '1', 07:10:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:02 INFO - 'PWD': '/builds/slave/test', 07:10:02 INFO - 'SHELL': '/bin/bash', 07:10:02 INFO - 'SHLVL': '1', 07:10:02 INFO - 'TERM': 'linux', 07:10:02 INFO - 'TMOUT': '86400', 07:10:02 INFO - 'USER': 'cltbld', 07:10:02 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:02 INFO - '_': '/tools/buildbot/bin/python'} 07:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:02 INFO - Downloading/unpacking flake8 07:10:02 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:10:02 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:10:02 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:10:02 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:10:02 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:10:02 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:10:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:10:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:10:04 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:10:04 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:10:04 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:10:04 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:10:04 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:10:04 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:10:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:10:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:10:04 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:10:04 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:10:04 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:10:04 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:10:04 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:10:04 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:10:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:10:05 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:10:05 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:10:05 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:10:05 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:10:05 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:10:05 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:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:10:05 INFO - no previously-included directories found matching 'docs/_build' 07:10:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:10:05 INFO - Running setup.py install for pep8 07:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:10:05 INFO - no previously-included directories found matching 'docs/_build' 07:10:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:10:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:10:05 INFO - Cleaning up... 07:10:05 INFO - Return code: 0 07:10:05 INFO - Running post-action listener: _resource_record_post_action 07:10:05 INFO - Running post-action listener: _start_resource_monitoring 07:10:05 INFO - Starting resource monitoring. 07:10:05 INFO - ##### 07:10:05 INFO - ##### Finished create-virtualenv step (success) 07:10:05 INFO - ##### 07:10:05 INFO - ##### 07:10:05 INFO - ##### Running install step. 07:10:05 INFO - ##### 07:10:05 INFO - Running pre-action listener: _resource_record_pre_action 07:10:05 INFO - Running main action method: install 07:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:10:05 INFO - Reading from file tmpfile_stdout 07:10:05 INFO - Detecting whether we're running mozinstall >=1.0... 07:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:10:05 INFO - Reading from file tmpfile_stdout 07:10:05 INFO - Output received: 07:10:05 INFO - Usage: mozinstall [options] installer 07:10:05 INFO - Options: 07:10:05 INFO - -h, --help show this help message and exit 07:10:05 INFO - -d DEST, --destination=DEST 07:10:05 INFO - Directory to install application into. [default: 07:10:05 INFO - "/builds/slave/test"] 07:10:05 INFO - --app=APP Application being installed. [default: firefox] 07:10:05 INFO - mkdir: /builds/slave/test/build/application 07:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:10:24 INFO - Reading from file tmpfile_stdout 07:10:24 INFO - Output received: 07:10:24 INFO - /builds/slave/test/build/application/firefox/firefox 07:10:24 INFO - Running post-action listener: _resource_record_post_action 07:10:24 INFO - ##### 07:10:24 INFO - ##### Finished install step (success) 07:10:24 INFO - ##### 07:10:24 INFO - ##### 07:10:24 INFO - ##### Running run-tests step. 07:10:24 INFO - ##### 07:10:24 INFO - Running pre-action listener: _resource_record_pre_action 07:10:24 INFO - Running main action method: run_tests 07:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:10:24 INFO - Python 2.7.3 07:10:24 INFO - Return code: 0 07:10:24 INFO - grabbing minidump binary from tooltool 07:10:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:10:24 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22209c0>, '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 0x22218c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2221d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:10:24 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:10:24 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:10:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:10:24 INFO - Return code: 0 07:10:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:10:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:10:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:10:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:10:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:10:25 INFO - Return code: 0 07:10:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:10:25 INFO - Using env: {'DISPLAY': ':0', 07:10:25 INFO - 'HOME': '/home/cltbld', 07:10:25 INFO - 'LANG': 'en_US.UTF-8', 07:10:25 INFO - 'LANGUAGE': 'en_US:en', 07:10:25 INFO - 'LOGNAME': 'cltbld', 07:10:25 INFO - 'MAIL': '/var/mail/cltbld', 07:10:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:10:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:25 INFO - 'MOZ_NO_REMOTE': '1', 07:10:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:10:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:10:25 INFO - 'NO_EM_RESTART': '1', 07:10:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:25 INFO - 'PWD': '/builds/slave/test', 07:10:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:10:25 INFO - 'SHELL': '/bin/bash', 07:10:25 INFO - 'SHLVL': '1', 07:10:25 INFO - 'TERM': 'linux', 07:10:25 INFO - 'TMOUT': '86400', 07:10:25 INFO - 'USER': 'cltbld', 07:10:25 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685', 07:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:25 INFO - '_': '/tools/buildbot/bin/python'} 07:10:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:10:25 INFO - mozversion application_buildid: 20160509043053 07:10:25 INFO - mozversion application_changeset: 043082cb7bd8490c60815f67fbd1f33323ad7663 07:10:25 INFO - mozversion application_display_name: Nightly 07:10:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:10:25 INFO - mozversion application_name: Firefox 07:10:25 INFO - mozversion application_remotingname: firefox 07:10:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:10:25 INFO - mozversion application_vendor: Mozilla 07:10:25 INFO - mozversion application_version: 49.0a1 07:10:25 INFO - mozversion platform_buildid: 20160509043053 07:10:25 INFO - mozversion platform_changeset: 043082cb7bd8490c60815f67fbd1f33323ad7663 07:10:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:10:25 INFO - mozversion platform_version: 49.0a1 07:10:25 INFO - using testdate: 1462803025 07:10:25 INFO - actual date: 1462803025 07:10:25 INFO - starting webserver on 'localhost:36942' 07:10:25 INFO - SUITE-START | Running 2 tests 07:10:25 INFO - TEST-START | dromaeo_css 07:10:25 INFO - operating with platform_type : linux_ 07:10:25 INFO - Initialising browser for dromaeo_css test... 07:10:25 INFO - TEST-INFO | started process 14798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpquS9lV/profile http://localhost:36942/getInfo.html) 07:10:29 INFO - PROCESS | 14798 | __metrics Screen width/height:1600/1200 07:10:29 INFO - PROCESS | 14798 | colorDepth:24 07:10:29 INFO - PROCESS | 14798 | Browser inner width/height: 1024/697 07:10:29 INFO - PROCESS | 14798 | __metrics 07:10:30 ERROR - PROCESS | 14798 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:10:30 INFO - PROCESS | 14798 | console.error: PushService: 07:10:30 INFO - PROCESS | 14798 | stateChangeProcessEnqueue: Error transitioning state 07:10:30 INFO - PROCESS | 14798 | UnknownError 07:10:31 INFO - TEST-INFO | 14798: exit 0 07:10:31 INFO - Browser initialized. 07:10:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:10:31 INFO - TEST-INFO | started process 14881 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpquS9lV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:10:43 INFO - PROCESS | 14881 | RSS: Main: 151777280 07:10:43 INFO - PROCESS | 14881 | 07:11:34 INFO - PROCESS | 14881 | 1462803094844 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:13:42 INFO - PROCESS | 14881 | Cycle 1(1): loaded http://localhost:36942/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36942/tests/dromaeo/cssquery-ext.html) 07:13:43 INFO - PROCESS | 14881 | RSS: Main: 252747776 07:13:43 INFO - PROCESS | 14881 | 07:16:46 INFO - PROCESS | 14881 | Cycle 1(1): loaded http://localhost:36942/tests/dromaeo/cssquery-ext.html (next: http://localhost:36942/tests/dromaeo/cssquery-jquery.html) 07:16:46 INFO - PROCESS | 14881 | RSS: Main: 235626496 07:16:46 INFO - PROCESS | 14881 | 07:19:48 INFO - PROCESS | 14881 | Cycle 1(1): loaded http://localhost:36942/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36942/tests/dromaeo/cssquery-mootools.html) 07:19:49 INFO - PROCESS | 14881 | RSS: Main: 239620096 07:19:49 INFO - PROCESS | 14881 | 07:22:51 INFO - PROCESS | 14881 | Cycle 1(1): loaded http://localhost:36942/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36942/tests/dromaeo/cssquery-prototype.html) 07:22:51 INFO - PROCESS | 14881 | RSS: Main: 230391808 07:22:51 INFO - PROCESS | 14881 | 07:25:54 INFO - PROCESS | 14881 | Cycle 1(1): loaded http://localhost:36942/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36942/tests/dromaeo/cssquery-yui.html) 07:25:55 INFO - PROCESS | 14881 | RSS: Main: 215597056 07:25:55 INFO - PROCESS | 14881 | 07:28:57 INFO - PROCESS | 14881 | Cycle 1(1): loaded http://localhost:36942/tests/dromaeo/cssquery-yui.html (next: http://localhost:36942/tests/dromaeo/cssquery-dojo.html) 07:28:58 INFO - PROCESS | 14881 | RSS: Main: 265277440 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | __start_tp_report 07:28:58 INFO - PROCESS | 14881 | _x_x_mozilla_page_load 07:28:58 INFO - PROCESS | 14881 | _x_x_mozilla_page_load_details 07:28:58 INFO - PROCESS | 14881 | |i|pagename|runs| 07:28:58 INFO - PROCESS | 14881 | |0;dojo.html;3154.0695494829038;3154.984225078875;3176.729977951874;3188.8724451021963;3218.806871587705;5333.173358129487;5337.172738225577;5429.56563474922;5446.2919820423285;5462.0441422751055;5460.754266058027;5462.672239665623;5492.505674489295;5496.31296087989;5627.409122042188;4887.340209071783;4955.405351357833;5034.924476132843;5061.3420255366755;5097.108006098936;4817.614590832725;5008.599312055045;5023.673461225027;5052.545270925616;5090.0328937501945;4867.829625963092;4874.244492103748;4881.5362540558945;4908.754562271872;4915.262710593403;4903.141950158743;4979.541572319521;4982.1281275776955;4992.850214493571;5039.143345631252;4796.664233503656;4922.704637721748;4930.359053323081;4945.357103576548;4997.975010124963;5391.29913111293;5453.400125986138;5480.369757477902;5483.972580137113;5495.725213739297;5857.912131317991;5931.228940237742;5937.139114828369;5939.168516407706;5972.104184372335;5691.06097493913;5796.333421656522;5826.708664566797;5903.26209223847;5925.377835327315;5552.77788888444;5559.054960656657;5560.721963901829;5561.721913904329;5563.99848027359;6022.638641681513;6036.215292012098;6037.366076561985;6040.486558642565;6049.395060494004;5531.3915469298345;5735.971320143372;5795.391483894215;5799.2460980073165;5803.5357171426185;5936.257967754031;5937.940620593825;5944.881102377928;5991.64050156992;5998.280206375263;5854.063349864001;5893.823185304448;5904.704764761831;5906.497947674496;5914.526837852962;5606.915896261559;5825.0679891217205;5865.941340586625;5870.236869206976;5912.320083190488;4468.664850136252;4470.128324976598;4475.664325175625;4478.193925093515;4512.616427603625;3892.338302488584;3954.4463775071513;3986.5614782373914;4018.0356714388336;4038.838446462167;5507.944920550824;5581.944180558143;5587.69267690281;5595.552355811526;5658.122990936411;5912.822615321547;5913.083472061838;5923.992921203406;5935.198748168942;5939.138824870456;5377.569794416438;5628.437156284421;5628.859278517988;5630.718464076862;5631.380548139701;5006.224035274466;5031.396232452056;5043.798248070109;5056.620753443506;5071.7971281149075;6061.666608336495;6089.025755879038;6089.117078023751;6095.512359011268;6097.664628445478;5658.236138121301;5901.026330655393;5901.704914754159;5905.645661260338;5913.763449461973;6024.789132380346;6059.09113632941;6061.848453788691;6101.176341194059;6103.6032657877095;5760;5771.797987070432;5783.826485205451;5784.161296612052;5821.94178058231;5661.943380566307;5718.628289161191;5852;5885.735141918538;5893.823185304448;5589.720513974204;5651.039323315129;5653.038983372754;5670.092785154356;5696.715164241854;5606.327240731139;5626.746796394254;5635.605507614428;5636.943630563642;5643.097104463266;5394.32570928634;5449.972750136224;5476.67139971603;5553.250311207935;5559.388467268516;5682.147677848356;5693.686847223443;5714.714264286852;5716.342620598518;5733.254676891977;5572.637778544382;5572.637778544382;5575.219469274224;5584.748686309044;5609.186667933209 07:28:58 INFO - PROCESS | 14881 | |1;ext.html;602.4818655955877;629.7543957856436;648.0829626079098;648.4585371215035;660.3165723476203;32173.67826321736;42576.36135457967;43960.34059489102;44047.11905761891;44060;43595.78202108985;43739.56260437395;43848.34227486582;43956.340654890126;43987.78006109965;7835.451518393715;8791.296696264284;8943.418677785956;8956.820863582725;10232.334898231627;10036;10435.06084452399;10437.58249670016;10439.895601043969;10455.790884182312;10458.738531536696;10487.73780655482;10492.842607360857;10497.475126243695;10501.10740587051;8026.759197224122;8202.58987050651;10344.741381465448;10395.740106497322;10609.681709548726;10401.739956501147;10464.267501274913;10477.790444191074;10491.527881245285;10496.265261431703;40976;41190.176196476204;41281.587184128075;41316.58683413157;41321.793391033156;3588.9282214355826;3599.4420864766003;3648.72634552407;3702.148505843671;3847.6537111659804;31466.21334466634;31731.36537269265;31965;32075.679243207735;32343;4080.5715399882884;4099.3031184698675;4128.483939507561;4285.400043993844;4300.6989510734265;323315;325627.7437225621;327110;327138.9999999976;327150;35205.29589408197;36051.278974420886;55782.72108639431;56720.43279567234;56889;36921.44617830735;37116;37169.81415092907;37229.81385093057;37244;15647.68704625901;15836.841631583768;15933.601659958362;15952.92023539898;15975.920120399323;11201;13251.933740331237;13403.865961340456;13521.729565408637;13882.652933676536;8588.312934965188;8618.525981071101;8626.094260102724;8668.566571671392;8689.435186712843;3307.8842240521467;3316.5688460499982;3342.9665703342657;3345.2974875276464;3368.3768502827056;1055.4511653939908;1076.3649446826253;1086.8043752124693;1105.5190991918541;1219.621917205669;31844.36311273808;32431.351372972407;32860.83569582184;33089.66910330866;34295.4855677165;1154.4054811771944;1159.513004538096;1172.9589464368705;1223.5656341998463;1290.6837824733;17138.40015599448;17155.828441715672;17169.399071032454;17190.570235744206;17193.65612687739;401191;402163;403405;405135;407212;55270.17094743582;55483.445165548634;55660;55727.72136139374;55780.442195577525;1308.064733715398;1413.639521921924;1429.2567864710293;1442.5095467541087;1459.0443259664723;31424.214394640316;32205.838970804994;32792.34415311728;33122;33378.83310583432;48013.759931201515;48316.51683483261;48486.5151348482;48544.271835923195;48680.5131948676;349265;352549;352712;353153;353990;25296.873515632305;37828.62171378251;38753.418698718975;39243.607563925136;39392.40911386274;51795.48204518057;52493.7375313121;52839;56038.71980640071;56565.151522726366;40274.59725402708;41779;42383;42446.9388463705;46428.767856160506;42041;42296;42663.57336426596;43094.56905430905;43624.56375436332;41973.160536788484;42688.35967460553;43056.78471607622;43303.566964329955;43316.78341608272;35691.64308356883;37250.24993658416;37681.43477847889;37720.2455950874;38460.61539384571;28729.712702872705;29141.562876556443;29224.707752922197;29262.853685731436;29438.705612943595 07:28:58 INFO - PROCESS | 14881 | |2;jquery.html;409.202055990818;410.6181251436164;412.50087394252955;417.7994562609947;427.4656679151061;12890.871091289084;17362.65274694507;18855.434336969895;18870.905645471772;19508.51228719283;13712.286228398541;18024.27902883883;18757.531061723465;19131;20046.59906801863;5586.832395028146;5790.797322093727;5803.680797556132;5805;5815.302163740357;5708.08670612702;5782.219400380958;5788.71056447178;5788.913166302509;5794.913076303858;4062.4718786557705;5986.371430999748;6314.052892066181;6317.33667964864;6426;6331.936680633226;6361.173047503818;6369.8089057328125;6387.0100134479;6395.712192951281;5537.307836520435;5647.37878833328;5653.378128405872;5681.857953551153;5686.715664216773;22667.77332226673;22684.432889177737;22778.88610556953;23235.070597176094;23461.178858740033;2659.8936042558275;2729;2932;2942.0585412667956;3117.236277112122;13628.591142265748;14435.92782036083;14638.268086595628;15007.249637518189;15087.698246035017;1934.487360849376;1954;1970.1331414177716;1996.19154242532;2009.5980803839145;83169.58415207885;86359.56820215858;87042.56478717565;87271.12728872757;87660.56169719175;10315.008647492294;10441.269111162297;10715.83926241107;10956;10978.176636752276;15354;15526.913116081765;15527.534173974798;15532.301046452936;15562.299696513684;8068.919310806934;8197.590120494071;8259.141693119;8274.751757447166;8300.252977232078;7950.204979502119;8148.592570371457;8525.403221774417;8571.742847714579;8776.254018408506;5741.88516229673;6111.908321375183;6504.447121994589;6694.59832410057;6748.865022699519;1840.5674666453372;1922.5385907382129;1925.3261358524405;1976.6046790641929;1982.0783335748945;1006.7986402719339;1026.8026423453814;1047.8690163729534;1094.2832444748703;1148.0471208896595;18654;19078.52303692391;19532.92568908695;19661.803381966285;19927;1079.293063043692;1081.1891081688734;1114.509615769059;1142.2860712054965;1172.5661505243115;11171.608993685182;11463.082953363712;11569.6529104127;11622.825657615143;11647.59233426826;480872;502512;508367;508544;511146;50505.494945050814;55972.72013639906;76940;83432;85234;1067.69570672358;1075.5482697267166;1076.682378698286;1093.6718984304678;1099.8295264234057;17240.448359647693;17299.481015569552;17329.133543322532;17408.73886891723;17678.292868285123;37593.62406376011;38077;38697.61302387053;38990.4151437723;39809.60190398059;49554.504454954986;50976.74511627418;51007.48992510027;51056.48943510666;51111;8769.246150769744;8855.91144088551;8907.658104728274;9046.276297896022;9115.544222788967;12416;12797.42411591462;13364.398602063126;13787.862121378657;13792.241426721626;11736.295822250693;12043.33761643083;12162.513499460276;12171;12271.263724176579;11214.719632009264;11376.715582110513;11661.183717140058;11848.763024739284;11959.521619135137;11704.70738231517;12136;12277.534945474674;12590.874091259337;12628.179168354027;8930.374873758774;9003.099690030944;9028.09719028092;9048.909510904807;9141.77145571366;8893.599788009684;11231.157663175458;11745.706357341134;11787.587434439753;11927.522899084286 07:28:58 INFO - PROCESS | 14881 | |3;mootools.html;1363.5091367107834;1542.1749364090215;1549.8527639874214;1732.5408766676833;1862.9254829806816;8326.542040187787;8736.694215702444;8747.956260218698;8947.86578201326;8955.910440895588;8665.870011949826;8835.690750823722;8838.734837954871;8863.246624036965;8867.733967980956;2095.8742475451522;2362.893669784864;2381.785639292459;2424.769646883552;2441.035790862617;2462.2367066209445;2480.6775119234476;2481.689788776403;2493.3891196656846;2494.0148641286683;2435.050330371148;2458.2379462366725;2462.495188486356;2463.4949835283815;2463.519613675334;2057.5884823035453;2338.088145623208;2423.3941514621256;2423.903043878243;2434.8782560871887;2345.542619189259;2347.507023525062;2353.3998830298337;2353.5292941411617;2376.4652953085592;8880.73357799267;8911.15344042315;8966.417182883091;8975.326850486239;8979.820403591892;5413.323334583177;5490.094134467808;5494.36814766299;5511.669299842022;5515.172724091378;3426.9485957710654;3431.571053618298;3502.824858757052;3570.767900086512;3583.964160358389;1117.6926345255038;1121.7644294698123;1144.5021415684205;1152.7262375075588;1203.0736333023524;12681.112322137455;12718.364081795966;12788.296643684595;12813.423395947204;12829;4603.286490593963;4635.907281854344;4731.455882573479;4736.8578942631775;4754.191787396151;9858.753531161778;9871.40771553709;9910.405375677337;9929.900700992897;9945.950270248602;4739.810407583727;5326.893462130735;5332.440093790174;5395.082835917903;5429.701366424805;4937.654364194544;5044.268581055726;5044.924326135111;5090.796368145307;5112.0031593839585;3785.091578021255;3828.3874580067613;3835.808209589565;3918.882433526942;3974.191742757039;1104.3539529375405;1114;1123.163243383668;1142.605800998654;1146.6297774159798;910.886139232596;1022.8977102289712;1046.2310202001522;1050.76883085721;1069.4064794039227;6241.687915604201;6261.8121456355475;6332.620042797447;6339.175907132136;6353.5234857385885;466.54977946282;478.547772355128;481.40065618305437;490.4776413120016;492.0822665728391;4237.237297286456;4312.223799716018;4350.108227813291;4351.521332653406;4353.824467393849;11095.223334366678;11509.76980460386;11602.07183425324;11607.883921160848;11617.419129043514;11240.550377984953;11333.489992950337;11359.375234362024;11386.202965792481;11426.714332141762;8003.83992320162;8303.252707256255;8484.872726909101;8562.529060901586;8577;6036.517078633611;6124;6203.7828675996125;6234.1583886174785;6267.059941008885;10307.742306442398;10402.895971040192;10522.158227341948;10529.57881684724;10532.315399499008;9929.602815887572;10180.389176649425;10203.081722644998;10329.328593641389;10705.785884282426;6930.965345173242;7615.390768738598;7814.843703126019;7930.524168549906;8122.065962414398;8737.344699147463;8739.257163141205;8780.121987801169;8804.647814087364;8819;7497.775066748006;7614.543127412373;7740.961295193488;7775.455718099679;8077.434579579608;7660;7899.289063984269;8072.031356237289;8186.181381861767;8201.22088401601;6953.304669533006;6993.300669932966;7048.541844780073;7180.60506672138;7186.928130718626;5366.597505187205;5393.298871146726;5439.673619582838;5586.273784408001;5590.748416321194;5132.255822905732;5250.474952504719;5271.051210781942;5323.1216849219445;5339.759710812945 07:28:58 INFO - PROCESS | 14881 | |4;prototype.html;339.57892213655066;342.2214462098725;342.717258261934;349.90727457223824;352.6121266606734;5284.682919024856;5302.416734159244;5305.018571564254;5307.734613269336;5325.295905310302;5270.710110943896;5288.550473209782;5297.841064768054;5322.467753224676;5346.946530534694;3964.5440774311037;3989.5810939851335;4109.383592461125;4134.255833949888;4145.55412306739;4087.6116768907054;4098.7130900836955;4106.117184805266;4107.17856428714;4121.608447197526;3932.351162058257;4077.938830917524;4098.8565400211;4113.588641135892;4125.566815484361;4026.0136266614722;4108.671306295504;4136.758972308296;4136.875893723193;4137.793110344471;3670.7614005089586;3685.963140368589;3691.446283057536;3694.390425579803;3708.03591066322;5100.974495127538;5103.2855400244;5104.821331253425;5110.718910459923;5151.021305951857;4575.702579332333;4709.882252943669;4739.170645137122;4765.880852978668;4779.713217206944;5386.676799392049;5414.296141501619;5431.728413579305;5463.235147079412;5482.890342193174;4052.4326594276827;4059.756414615103;4071.4299998000006;4072.8370865165357;4084.101497670508;5568.443155684439;5583.860403489945;5666.235058267122;5711.228984087136;5736.36513593628;4151.813168407429;4166.791660417028;4170.311898536768;4175.582441755861;4178.791060446965;4273.465816772903;4279.593438623326;4286.507051689034;4286.678499112579;4287.142571485653;4672.976635116803;4686.297055441711;4798.184308667535;4809.326694262806;4815.686980346266;5441.0684207105905;5500.367457742331;5513;5530.5022547970875;5576.358718747315;1421.175718083509;1428.5857101440465;1435.4617018618019;1440.279860069965;1451.2235953764896;917.9862302065475;918.9816203676023;982.9901700983041;996.6860438961704;1009.2027298434155;5109.259157422153;5191.6884986900905;5211.557017653542;5250.658707184097;5250.901967838993;5400.567954563626;5460.399356070829;5496.7526461308535;5510.393856675762;5517.751701173376;5087.135187018216;5143.871403214875;5155.149400348975;5180.844574662766;5494.340679118452;2903.259668784489;2924.596193826975;2972.390659914712;2992.58103865459;3005.7745669074907;5631.352394474606;5692.6299790513485;5702.54379649627;5748.597598168088;5753.33836608787;5471.659443436383;5612.354579223442;5638.379778224392;5655.80204692842;5660.320761508646;5694.231278777312;5714.7428365723645;5743.310802703619;5786.247787787561;5797.826065218051;5284.656497327662;5523.944760552343;5537.473939975695;5586.86032849182;5589.972050139886;5491.588130890119;5500;5501.669899806025;5519.724013799214;5534.889302214014;5704.429557044263;5745.6552606843725;5767.9134812977;5806.506446952057;5844.766209351748;3827.8660246891227;3990.8802735916806;4003.9399409008306;4102.425660407485;4122.8456032311005;5659.962413531043;5712.314522257356;5729.570282228933;5780.063678667693;5786.161006653927;5345.54562862161;5434.456554344534;5581.051221292309;5609.775608975595;5620.971895140498;5562.749676264497;5583.748731307182;5604.159376093456;5610.270664813548;5611.352620796351;5310.867228319323;5505.559555235652;5513.82954800983;5516.365617953827;5528.502434780889;5582.330120385417;5602.607817452739;5608.186812912187;5671.801486947937;5694.402087780723;5510.586705997146;5534.280543529316;5546.530169505004;5548.196836028387;5550.306211723535 07:28:58 INFO - PROCESS | 14881 | |5;yui.html;775.0931410250007;780.9960950195249;874.3529787956913;883.8939327280725;891.4428482198626;13880.305984700763;14459.493917712882;14465.20441375724;14494;14513.49202777903;13321.733565328688;13366.66583335417;13366.866331336709;13371.331433428313;13373.264470454122;679.6533767778432;779.3998621061779;805.2269820971874;806.0609390060572;807.4267270238138;797.1948332184506;799.6081919859286;800.6437135474716;802.3741481644298;803.3813963248296;711.9679614417364;850.0776657326777;850.2985037344184;850.2985037344184;860.6686425726109;835.1564919431357;855.4396870050127;856.9442986205908;857.9227869491775;858.8668756342714;638.0811631250983;639.1467391032977;639.1563136659611;639.5523133806354;639.6897504710212;12392.194507357082;12427.813582796265;12433.191842530206;12453.937730311382;12464;12801.743965120646;12954.157979731288;13186.736265274641;13455.327233638376;13492.920566354667;11376.488058037408;11388.60139895108;11726.120540959462;11978.580749673807;12041.578544750979;4364.759938203398;4375.956240437618;4386.890327741832;4455.376247325377;4508.864734057984;52078;52723.945521089365;53775;53959.730201349135;54090.72954635201;9073.274138068939;9173.633054677872;10679.09227715653;10799.190060745475;10922.1808364373;19305.8069419304;19402.902985484983;19506.512337191685;19513.609727805364;19516;7599.316061554487;7602.619869006528;7822.726204582926;7863.921360786433;8003.719869804529;4440.378347031418;4512.571305725951;4512.661550383735;4603.5396460353695;4646.651501137429;3343.1976325681662;3421.298633780069;3423.17843717506;3433.176037750972;3435.4159792835276;621.1862460177182;630.9779157729549;653.3792896748014;666.9699863506154;716.1012928774459;644.4103645164715;653.0856800479366;745.22124380023;796.9322607578305;801.1067659559666;9251.814963700688;9260.7684807879;9357.719268421959;9389.953050234843;9627.422354658604;635.3868516881232;636.8153235561728;705.9152901651732;760.7413479417024;791.2562191539934;4370.453693288339;4564.6348292136545;4576.931346029813;4580.450345958439;4677.391939047902;62330.688346557974;63465.922726181794;63626.36373636297;63717.68141159357;63776.36223637704;56325.7183714087;56636.15045774317;56639.71680141655;57813.4218657808;59435;52407;52947.73526132345;53139.46860531423;53929.73035134799;54311.18533222005;8623.784405389664;8703.347248956226;8741.519216443157;8768.298536116969;8836.779080523038;34061.31877362489;34373.312533748685;34461.13847153841;34656;36393.45409818802;43884.12231755283;45759.54240457553;46162;46289.3056604158;46909.530904691885;11051.613193538187;11077.169212308949;11107.44462776874;11212.215144940103;11413.885861141282;12530.686732831753;12557.811632825698;12795.296258705861;12986.155899866479;13250.337483125997;12405.689857753629;12716.618501444971;12926.935365323114;12937.159084659466;13145.53990610324;11636.29498618852;11753.588624398106;11817.290962542274;11915;12480.687982800502;11362.94318528402;11477.368744718786;11756.94121529387;11816.881831181578;12120.27278363301;9260.953695231481;9458.054194580762;9618.903810961801;9714.80570388574;10170.440625765654;9706.854397183906;9955.20358371343;9997.750056248651;10087.142592879687;10823.837642435212 07:28:58 INFO - PROCESS | 14881 | __end_tp_report 07:28:58 INFO - PROCESS | 14881 | __start_cc_report 07:28:58 INFO - PROCESS | 14881 | _x_x_mozilla_cycle_collect,859 07:28:58 INFO - PROCESS | 14881 | __end_cc_report 07:28:58 INFO - PROCESS | 14881 | __startTimestamp1462804138137__endTimestamp 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | ------- Summary: start ------- 07:28:58 INFO - PROCESS | 14881 | Number of tests: 6 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | [#0] dojo.html Cycles:165 Average:5434.90 Median:5609.19 stddev:623.81 (11.1%) stddev-sans-first:599.51 07:28:58 INFO - PROCESS | 14881 | Values: 3154.1 3155.0 3176.7 3188.9 3218.8 5333.2 5337.2 5429.6 5446.3 5462.0 5460.8 5462.7 5492.5 5496.3 5627.4 4887.3 4955.4 5034.9 5061.3 5097.1 4817.6 5008.6 5023.7 5052.5 5090.0 4867.8 4874.2 4881.5 4908.8 4915.3 4903.1 4979.5 4982.1 4992.9 5039.1 4796.7 4922.7 4930.4 4945.4 4998.0 5391.3 5453.4 5480.4 5484.0 5495.7 5857.9 5931.2 5937.1 5939.2 5972.1 5691.1 5796.3 5826.7 5903.3 5925.4 5552.8 5559.1 5560.7 5561.7 5564.0 6022.6 6036.2 6037.4 6040.5 6049.4 5531.4 5736.0 5795.4 5799.2 5803.5 5936.3 5937.9 5944.9 5991.6 5998.3 5854.1 5893.8 5904.7 5906.5 5914.5 5606.9 5825.1 5865.9 5870.2 5912.3 4468.7 4470.1 4475.7 4478.2 4512.6 3892.3 3954.4 3986.6 4018.0 4038.8 5507.9 5581.9 5587.7 5595.6 5658.1 5912.8 5913.1 5924.0 5935.2 5939.1 5377.6 5628.4 5628.9 5630.7 5631.4 5006.2 5031.4 5043.8 5056.6 5071.8 6061.7 6089.0 6089.1 6095.5 6097.7 5658.2 5901.0 5901.7 5905.6 5913.8 6024.8 6059.1 6061.8 6101.2 6103.6 5760.0 5771.8 5783.8 5784.2 5821.9 5661.9 5718.6 5852.0 5885.7 5893.8 5589.7 5651.0 5653.0 5670.1 5696.7 5606.3 5626.7 5635.6 5636.9 5643.1 5394.3 5450.0 5476.7 5553.3 5559.4 5682.1 5693.7 5714.7 5716.3 5733.3 5572.6 5572.6 5575.2 5584.7 5609.2 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | [#1] ext.html Cycles:180 Average:52783.31 Median:19850.00 stddev:95179.04 (479.5%) stddev-sans-first:95365.41 07:28:58 INFO - PROCESS | 14881 | Values: 602.5 629.8 648.1 648.5 660.3 32173.7 42576.4 43960.3 44047.1 44060.0 43595.8 43739.6 43848.3 43956.3 43987.8 7835.5 8791.3 8943.4 8956.8 10232.3 10036.0 10435.1 10437.6 10439.9 10455.8 10458.7 10487.7 10492.8 10497.5 10501.1 8026.8 8202.6 10344.7 10395.7 10609.7 10401.7 10464.3 10477.8 10491.5 10496.3 40976.0 41190.2 41281.6 41316.6 41321.8 3588.9 3599.4 3648.7 3702.1 3847.7 31466.2 31731.4 31965.0 32075.7 32343.0 4080.6 4099.3 4128.5 4285.4 4300.7 323315.0 325627.7 327110.0 327139.0 327150.0 35205.3 36051.3 55782.7 56720.4 56889.0 36921.4 37116.0 37169.8 37229.8 37244.0 15647.7 15836.8 15933.6 15952.9 15975.9 11201.0 13251.9 13403.9 13521.7 13882.7 8588.3 8618.5 8626.1 8668.6 8689.4 3307.9 3316.6 3343.0 3345.3 3368.4 1055.5 1076.4 1086.8 1105.5 1219.6 31844.4 32431.4 32860.8 33089.7 34295.5 1154.4 1159.5 1173.0 1223.6 1290.7 17138.4 17155.8 17169.4 17190.6 17193.7 401191.0 402163.0 403405.0 405135.0 407212.0 55270.2 55483.4 55660.0 55727.7 55780.4 1308.1 1413.6 1429.3 1442.5 1459.0 31424.2 32205.8 32792.3 33122.0 33378.8 48013.8 48316.5 48486.5 48544.3 48680.5 349265.0 352549.0 352712.0 353153.0 353990.0 25296.9 37828.6 38753.4 39243.6 39392.4 51795.5 52493.7 52839.0 56038.7 56565.2 40274.6 41779.0 42383.0 42446.9 46428.8 42041.0 42296.0 42663.6 43094.6 43624.6 41973.2 42688.4 43056.8 43303.6 43316.8 35691.6 37250.2 37681.4 37720.2 38460.6 28729.7 29141.6 29224.7 29262.9 29438.7 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | [#2] jquery.html Cycles:180 Average:28801.39 Median:22731.66 stddev:82395.31 (362.5%) stddev-sans-first:82598.87 07:28:58 INFO - PROCESS | 14881 | Values: 409.2 410.6 412.5 417.8 427.5 12890.9 17362.7 18855.4 18870.9 19508.5 13712.3 18024.3 18757.5 19131.0 20046.6 5586.8 5790.8 5803.7 5805.0 5815.3 5708.1 5782.2 5788.7 5788.9 5794.9 4062.5 5986.4 6314.1 6317.3 6426.0 6331.9 6361.2 6369.8 6387.0 6395.7 5537.3 5647.4 5653.4 5681.9 5686.7 22667.8 22684.4 22778.9 23235.1 23461.2 2659.9 2729.0 2932.0 2942.1 3117.2 13628.6 14435.9 14638.3 15007.2 15087.7 1934.5 1954.0 1970.1 1996.2 2009.6 83169.6 86359.6 87042.6 87271.1 87660.6 10315.0 10441.3 10715.8 10956.0 10978.2 15354.0 15526.9 15527.5 15532.3 15562.3 8068.9 8197.6 8259.1 8274.8 8300.3 7950.2 8148.6 8525.4 8571.7 8776.3 5741.9 6111.9 6504.4 6694.6 6748.9 1840.6 1922.5 1925.3 1976.6 1982.1 1006.8 1026.8 1047.9 1094.3 1148.0 18654.0 19078.5 19532.9 19661.8 19927.0 1079.3 1081.2 1114.5 1142.3 1172.6 11171.6 11463.1 11569.7 11622.8 11647.6 480872.0 502512.0 508367.0 508544.0 511146.0 50505.5 55972.7 76940.0 83432.0 85234.0 1067.7 1075.5 1076.7 1093.7 1099.8 17240.4 17299.5 17329.1 17408.7 17678.3 37593.6 38077.0 38697.6 38990.4 39809.6 49554.5 50976.7 51007.5 51056.5 51111.0 8769.2 8855.9 8907.7 9046.3 9115.5 12416.0 12797.4 13364.4 13787.9 13792.2 11736.3 12043.3 12162.5 12171.0 12271.3 11214.7 11376.7 11661.2 11848.8 11959.5 11704.7 12136.0 12277.5 12590.9 12628.2 8930.4 9003.1 9028.1 9048.9 9141.8 8893.6 11231.2 11745.7 11787.6 11927.5 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | [#3] mootools.html Cycles:180 Average:5920.32 Median:479.97 stddev:3390.96 (706.5%) stddev-sans-first:3383.18 07:28:58 INFO - PROCESS | 14881 | Values: 1363.5 1542.2 1549.9 1732.5 1862.9 8326.5 8736.7 8748.0 8947.9 8955.9 8665.9 8835.7 8838.7 8863.2 8867.7 2095.9 2362.9 2381.8 2424.8 2441.0 2462.2 2480.7 2481.7 2493.4 2494.0 2435.1 2458.2 2462.5 2463.5 2463.5 2057.6 2338.1 2423.4 2423.9 2434.9 2345.5 2347.5 2353.4 2353.5 2376.5 8880.7 8911.2 8966.4 8975.3 8979.8 5413.3 5490.1 5494.4 5511.7 5515.2 3426.9 3431.6 3502.8 3570.8 3584.0 1117.7 1121.8 1144.5 1152.7 1203.1 12681.1 12718.4 12788.3 12813.4 12829.0 4603.3 4635.9 4731.5 4736.9 4754.2 9858.8 9871.4 9910.4 9929.9 9946.0 4739.8 5326.9 5332.4 5395.1 5429.7 4937.7 5044.3 5044.9 5090.8 5112.0 3785.1 3828.4 3835.8 3918.9 3974.2 1104.4 1114.0 1123.2 1142.6 1146.6 910.9 1022.9 1046.2 1050.8 1069.4 6241.7 6261.8 6332.6 6339.2 6353.5 466.5 478.5 481.4 490.5 492.1 4237.2 4312.2 4350.1 4351.5 4353.8 11095.2 11509.8 11602.1 11607.9 11617.4 11240.6 11333.5 11359.4 11386.2 11426.7 8003.8 8303.3 8484.9 8562.5 8577.0 6036.5 6124.0 6203.8 6234.2 6267.1 10307.7 10402.9 10522.2 10529.6 10532.3 9929.6 10180.4 10203.1 10329.3 10705.8 6931.0 7615.4 7814.8 7930.5 8122.1 8737.3 8739.3 8780.1 8804.6 8819.0 7497.8 7614.5 7741.0 7775.5 8077.4 7660.0 7899.3 8072.0 8186.2 8201.2 6953.3 6993.3 7048.5 7180.6 7186.9 5366.6 5393.3 5439.7 5586.3 5590.7 5132.3 5250.5 5271.1 5323.1 5339.8 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | [#4] prototype.html Cycles:180 Average:4647.36 Median:5303.72 stddev:1344.37 (25.3%) stddev-sans-first:1308.68 07:28:58 INFO - PROCESS | 14881 | Values: 339.6 342.2 342.7 349.9 352.6 5284.7 5302.4 5305.0 5307.7 5325.3 5270.7 5288.6 5297.8 5322.5 5346.9 3964.5 3989.6 4109.4 4134.3 4145.6 4087.6 4098.7 4106.1 4107.2 4121.6 3932.4 4077.9 4098.9 4113.6 4125.6 4026.0 4108.7 4136.8 4136.9 4137.8 3670.8 3686.0 3691.4 3694.4 3708.0 5101.0 5103.3 5104.8 5110.7 5151.0 4575.7 4709.9 4739.2 4765.9 4779.7 5386.7 5414.3 5431.7 5463.2 5482.9 4052.4 4059.8 4071.4 4072.8 4084.1 5568.4 5583.9 5666.2 5711.2 5736.4 4151.8 4166.8 4170.3 4175.6 4178.8 4273.5 4279.6 4286.5 4286.7 4287.1 4673.0 4686.3 4798.2 4809.3 4815.7 5441.1 5500.4 5513.0 5530.5 5576.4 1421.2 1428.6 1435.5 1440.3 1451.2 918.0 919.0 983.0 996.7 1009.2 5109.3 5191.7 5211.6 5250.7 5250.9 5400.6 5460.4 5496.8 5510.4 5517.8 5087.1 5143.9 5155.1 5180.8 5494.3 2903.3 2924.6 2972.4 2992.6 3005.8 5631.4 5692.6 5702.5 5748.6 5753.3 5471.7 5612.4 5638.4 5655.8 5660.3 5694.2 5714.7 5743.3 5786.2 5797.8 5284.7 5523.9 5537.5 5586.9 5590.0 5491.6 5500.0 5501.7 5519.7 5534.9 5704.4 5745.7 5767.9 5806.5 5844.8 3827.9 3990.9 4003.9 4102.4 4122.8 5660.0 5712.3 5729.6 5780.1 5786.2 5345.5 5434.5 5581.1 5609.8 5621.0 5562.7 5583.7 5604.2 5610.3 5611.4 5310.9 5505.6 5513.8 5516.4 5528.5 5582.3 5602.6 5608.2 5671.8 5694.4 5510.6 5534.3 5546.5 5548.2 5550.3 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - PROCESS | 14881 | [#5] yui.html Cycles:180 Average:14791.22 Median:49493.77 stddev:17527.15 (35.4%) stddev-sans-first:17544.71 07:28:58 INFO - PROCESS | 14881 | Values: 775.1 781.0 874.4 883.9 891.4 13880.3 14459.5 14465.2 14494.0 14513.5 13321.7 13366.7 13366.9 13371.3 13373.3 679.7 779.4 805.2 806.1 807.4 797.2 799.6 800.6 802.4 803.4 712.0 850.1 850.3 850.3 860.7 835.2 855.4 856.9 857.9 858.9 638.1 639.1 639.2 639.6 639.7 12392.2 12427.8 12433.2 12453.9 12464.0 12801.7 12954.2 13186.7 13455.3 13492.9 11376.5 11388.6 11726.1 11978.6 12041.6 4364.8 4376.0 4386.9 4455.4 4508.9 52078.0 52723.9 53775.0 53959.7 54090.7 9073.3 9173.6 10679.1 10799.2 10922.2 19305.8 19402.9 19506.5 19513.6 19516.0 7599.3 7602.6 7822.7 7863.9 8003.7 4440.4 4512.6 4512.7 4603.5 4646.7 3343.2 3421.3 3423.2 3433.2 3435.4 621.2 631.0 653.4 667.0 716.1 644.4 653.1 745.2 796.9 801.1 9251.8 9260.8 9357.7 9390.0 9627.4 635.4 636.8 705.9 760.7 791.3 4370.5 4564.6 4576.9 4580.5 4677.4 62330.7 63465.9 63626.4 63717.7 63776.4 56325.7 56636.2 56639.7 57813.4 59435.0 52407.0 52947.7 53139.5 53929.7 54311.2 8623.8 8703.3 8741.5 8768.3 8836.8 34061.3 34373.3 34461.1 34656.0 36393.5 43884.1 45759.5 46162.0 46289.3 46909.5 11051.6 11077.2 11107.4 11212.2 11413.9 12530.7 12557.8 12795.3 12986.2 13250.3 12405.7 12716.6 12926.9 12937.2 13145.5 11636.3 11753.6 11817.3 11915.0 12480.7 11362.9 11477.4 11756.9 11816.9 12120.3 9261.0 9458.1 9618.9 9714.8 10170.4 9706.9 9955.2 9997.8 10087.1 10823.8 07:28:58 INFO - PROCESS | 14881 | -------- Summary: end -------- 07:28:58 INFO - PROCESS | 14881 | 07:28:58 INFO - TEST-INFO | 14881: exit 0 07:28:58 INFO - TEST-OK | dromaeo_css | took 1113425ms 07:28:58 INFO - TEST-START | kraken 07:28:58 INFO - operating with platform_type : linux_ 07:28:58 INFO - Initialising browser for kraken test... 07:28:58 INFO - TEST-INFO | started process 15064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9J9Y46/profile http://localhost:36942/getInfo.html) 07:29:03 INFO - PROCESS | 15064 | __metrics Screen width/height:1600/1200 07:29:03 INFO - PROCESS | 15064 | colorDepth:24 07:29:03 INFO - PROCESS | 15064 | Browser inner width/height: 1024/697 07:29:03 INFO - PROCESS | 15064 | __metrics 07:29:04 ERROR - PROCESS | 15064 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:29:05 INFO - TEST-INFO | 15064: exit 0 07:29:05 INFO - Browser initialized. 07:29:05 INFO - Running cycle 1/1 for kraken test... 07:29:05 INFO - TEST-INFO | started process 15149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9J9Y46/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:29:16 INFO - PROCESS | 15149 | RSS: Main: 151715840 07:29:16 INFO - PROCESS | 15149 | 07:29:19 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36942/tests/kraken/driver.html?testName=audio-beat-detection) 07:29:20 INFO - PROCESS | 15149 | RSS: Main: 208150528 07:29:20 INFO - PROCESS | 15149 | 07:29:23 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36942/tests/kraken/driver.html?testName=audio-dft) 07:29:23 INFO - PROCESS | 15149 | RSS: Main: 196988928 07:29:23 INFO - PROCESS | 15149 | 07:29:26 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36942/tests/kraken/driver.html?testName=audio-fft) 07:29:27 INFO - PROCESS | 15149 | RSS: Main: 264425472 07:29:27 INFO - PROCESS | 15149 | 07:29:29 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36942/tests/kraken/driver.html?testName=audio-oscillator) 07:29:30 INFO - PROCESS | 15149 | RSS: Main: 174387200 07:29:30 INFO - PROCESS | 15149 | 07:29:32 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36942/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:29:33 INFO - PROCESS | 15149 | RSS: Main: 178159616 07:29:33 INFO - PROCESS | 15149 | 07:29:36 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36942/tests/kraken/driver.html?testName=imaging-darkroom) 07:29:36 INFO - PROCESS | 15149 | RSS: Main: 213798912 07:29:36 INFO - PROCESS | 15149 | 07:29:40 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36942/tests/kraken/driver.html?testName=imaging-desaturate) 07:29:41 INFO - PROCESS | 15149 | RSS: Main: 214376448 07:29:41 INFO - PROCESS | 15149 | 07:29:43 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36942/tests/kraken/driver.html?testName=json-parse-financial) 07:29:44 INFO - PROCESS | 15149 | RSS: Main: 214937600 07:29:44 INFO - PROCESS | 15149 | 07:29:46 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36942/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:29:46 INFO - PROCESS | 15149 | RSS: Main: 204537856 07:29:46 INFO - PROCESS | 15149 | 07:29:48 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:29:49 INFO - PROCESS | 15149 | RSS: Main: 206065664 07:29:49 INFO - PROCESS | 15149 | 07:29:51 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:29:52 INFO - PROCESS | 15149 | RSS: Main: 214044672 07:29:52 INFO - PROCESS | 15149 | 07:29:54 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:29:55 INFO - PROCESS | 15149 | RSS: Main: 224198656 07:29:55 INFO - PROCESS | 15149 | 07:29:58 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:29:58 INFO - PROCESS | 15149 | RSS: Main: 220020736 07:29:58 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | Cycle 1(1): loaded http://localhost:36942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36942/tests/kraken/driver.html?testName=ai-astar) 07:30:00 INFO - PROCESS | 15149 | RSS: Main: 266956800 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | __start_tp_report 07:30:00 INFO - PROCESS | 15149 | _x_x_mozilla_page_load 07:30:00 INFO - PROCESS | 15149 | _x_x_mozilla_page_load_details 07:30:00 INFO - PROCESS | 15149 | |i|pagename|runs| 07:30:00 INFO - PROCESS | 15149 | |0;ai-astar;110;106;109;112;111;111;109;111;111;110 07:30:00 INFO - PROCESS | 15149 | |1;audio-beat-detection;155;124;153;225;132;152;154;134;195;128 07:30:00 INFO - PROCESS | 15149 | |2;audio-dft;162;162;161;159;162;162;161;161;161;161 07:30:00 INFO - PROCESS | 15149 | |3;audio-fft;75;78;76;150;76;75;77;75;130;76 07:30:00 INFO - PROCESS | 15149 | |4;audio-oscillator;93;93;150;91;97;92;121;93;93;126 07:30:00 INFO - PROCESS | 15149 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;116;117;117 07:30:00 INFO - PROCESS | 15149 | |6;imaging-darkroom;145;146;147;146;146;145;146;146;146;146 07:30:00 INFO - PROCESS | 15149 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 07:30:00 INFO - PROCESS | 15149 | |8;json-parse-financial;58;59;58;58;58;59;59;109;55;56 07:30:00 INFO - PROCESS | 15149 | |9;json-stringify-tinderbox;59;59;59;59;60;60;59;59;59;59 07:30:00 INFO - PROCESS | 15149 | |10;stanford-crypto-aes;86;79;81;79;80;81;80;80;81;80 07:30:00 INFO - PROCESS | 15149 | |11;stanford-crypto-ccm;133;124;126;123;124;124;126;126;190;125 07:30:00 INFO - PROCESS | 15149 | |12;stanford-crypto-pbkdf2;175;169;177;172;169;173;169;167;168;168 07:30:00 INFO - PROCESS | 15149 | |13;stanford-crypto-sha256-iterative;65;66;68;64;68;65;66;65;63;64 07:30:00 INFO - PROCESS | 15149 | __end_tp_report 07:30:00 INFO - PROCESS | 15149 | __start_cc_report 07:30:00 INFO - PROCESS | 15149 | _x_x_mozilla_cycle_collect,1837 07:30:00 INFO - PROCESS | 15149 | __end_cc_report 07:30:00 INFO - PROCESS | 15149 | __startTimestamp1462804200758__endTimestamp 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | ------- Summary: start ------- 07:30:00 INFO - PROCESS | 15149 | Number of tests: 14 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:1.70 (1.5%) stddev-sans-first:1.80 07:30:00 INFO - PROCESS | 15149 | Values: 110.0 106.0 109.0 112.0 111.0 111.0 109.0 111.0 111.0 110.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#1] audio-beat-detection Cycles:10 Average:155.20 Median:153.50 stddev:31.93 (20.8%) stddev-sans-first:33.86 07:30:00 INFO - PROCESS | 15149 | Values: 155.0 124.0 153.0 225.0 132.0 152.0 154.0 134.0 195.0 128.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.92 (0.6%) stddev-sans-first:0.93 07:30:00 INFO - PROCESS | 15149 | Values: 162.0 162.0 161.0 159.0 162.0 162.0 161.0 161.0 161.0 161.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#3] audio-fft Cycles:10 Average:88.80 Median:76.00 stddev:27.41 (36.1%) stddev-sans-first:28.61 07:30:00 INFO - PROCESS | 15149 | Values: 75.0 78.0 76.0 150.0 76.0 75.0 77.0 75.0 130.0 76.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#4] audio-oscillator Cycles:10 Average:104.90 Median:93.00 stddev:20.35 (21.9%) stddev-sans-first:21.12 07:30:00 INFO - PROCESS | 15149 | Values: 93.0 93.0 150.0 91.0 97.0 92.0 121.0 93.0 93.0 126.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:30:00 INFO - PROCESS | 15149 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 07:30:00 INFO - PROCESS | 15149 | Values: 145.0 146.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 07:30:00 INFO - PROCESS | 15149 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:58.00 stddev:16.25 (28.0%) stddev-sans-first:17.14 07:30:00 INFO - PROCESS | 15149 | Values: 58.0 59.0 58.0 58.0 58.0 59.0 59.0 109.0 55.0 56.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 07:30:00 INFO - PROCESS | 15149 | Values: 59.0 59.0 59.0 59.0 60.0 60.0 59.0 59.0 59.0 59.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.50 stddev:2.00 (2.5%) stddev-sans-first:0.78 07:30:00 INFO - PROCESS | 15149 | Values: 86.0 79.0 81.0 79.0 80.0 81.0 80.0 80.0 81.0 80.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:126.00 stddev:20.53 (16.3%) stddev-sans-first:21.78 07:30:00 INFO - PROCESS | 15149 | Values: 133.0 124.0 126.0 123.0 124.0 124.0 126.0 126.0 190.0 125.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:170.50 stddev:3.37 (2.0%) stddev-sans-first:3.19 07:30:00 INFO - PROCESS | 15149 | Values: 175.0 169.0 177.0 172.0 169.0 173.0 169.0 167.0 168.0 168.0 07:30:00 INFO - PROCESS | 15149 | 07:30:00 INFO - PROCESS | 15149 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.65 (2.5%) stddev-sans-first:1.74 07:30:00 INFO - PROCESS | 15149 | Values: 65.0 66.0 68.0 64.0 68.0 65.0 66.0 65.0 63.0 64.0 07:30:00 INFO - PROCESS | 15149 | -------- Summary: end -------- 07:30:00 INFO - PROCESS | 15149 | 07:30:01 INFO - TEST-INFO | 15149: exit 0 07:30:01 INFO - TEST-OK | kraken | took 63047ms 07:30:01 INFO - SUITE-END | took 1176s 07:30:01 INFO - Completed test suite (00:19:36) 07:30:01 INFO - javascript benchmark 07:30:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3154.0695494829038, 3154.984225078875, 3176.729977951874, 3188.8724451021963, 3218.806871587705, 5333.173358129487, 5337.172738225577, 5429.56563474922, 5446.2919820423285, 5462.0441422751055, 5460.754266058027, 5462.672239665623, 5492.505674489295, 5496.31296087989, 5627.409122042188, 4887.340209071783, 4955.405351357833, 5034.924476132843, 5061.3420255366755, 5097.108006098936, 4817.614590832725, 5008.599312055045, 5023.673461225027, 5052.545270925616, 5090.0328937501945, 4867.829625963092, 4874.244492103748, 4881.5362540558945, 4908.754562271872, 4915.262710593403, 4903.141950158743, 4979.541572319521, 4982.1281275776955, 4992.850214493571, 5039.143345631252, 4796.664233503656, 4922.704637721748, 4930.359053323081, 4945.357103576548, 4997.975010124963, 5391.29913111293, 5453.400125986138, 5480.369757477902, 5483.972580137113, 5495.725213739297, 5857.912131317991, 5931.228940237742, 5937.139114828369, 5939.168516407706, 5972.104184372335, 5691.06097493913, 5796.333421656522, 5826.708664566797, 5903.26209223847, 5925.377835327315, 5552.77788888444, 5559.054960656657, 5560.721963901829, 5561.721913904329, 5563.99848027359, 6022.638641681513, 6036.215292012098, 6037.366076561985, 6040.486558642565, 6049.395060494004, 5531.3915469298345, 5735.971320143372, 5795.391483894215, 5799.2460980073165, 5803.5357171426185, 5936.257967754031, 5937.940620593825, 5944.881102377928, 5991.64050156992, 5998.280206375263, 5854.063349864001, 5893.823185304448, 5904.704764761831, 5906.497947674496, 5914.526837852962, 5606.915896261559, 5825.0679891217205, 5865.941340586625, 5870.236869206976, 5912.320083190488, 4468.664850136252, 4470.128324976598, 4475.664325175625, 4478.193925093515, 4512.616427603625, 3892.338302488584, 3954.4463775071513, 3986.5614782373914, 4018.0356714388336, 4038.838446462167, 5507.944920550824, 5581.944180558143, 5587.69267690281, 5595.552355811526, 5658.122990936411, 5912.822615321547, 5913.083472061838, 5923.992921203406, 5935.198748168942, 5939.138824870456, 5377.569794416438, 5628.437156284421, 5628.859278517988, 5630.718464076862, 5631.380548139701, 5006.224035274466, 5031.396232452056, 5043.798248070109, 5056.620753443506, 5071.7971281149075, 6061.666608336495, 6089.025755879038, 6089.117078023751, 6095.512359011268, 6097.664628445478, 5658.236138121301, 5901.026330655393, 5901.704914754159, 5905.645661260338, 5913.763449461973, 6024.789132380346, 6059.09113632941, 6061.848453788691, 6101.176341194059, 6103.6032657877095, 5760.0, 5771.797987070432, 5783.826485205451, 5784.161296612052, 5821.94178058231, 5661.943380566307, 5718.628289161191, 5852.0, 5885.735141918538, 5893.823185304448, 5589.720513974204, 5651.039323315129, 5653.038983372754, 5670.092785154356, 5696.715164241854, 5606.327240731139, 5626.746796394254, 5635.605507614428, 5636.943630563642, 5643.097104463266, 5394.32570928634, 5449.972750136224, 5476.67139971603, 5553.250311207935, 5559.388467268516, 5682.147677848356, 5693.686847223443, 5714.714264286852, 5716.342620598518, 5733.254676891977, 5572.637778544382, 5572.637778544382, 5575.219469274224, 5584.748686309044, 5609.186667933209], "value": 5392.618491122162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.4818655955877, 629.7543957856436, 648.0829626079098, 648.4585371215035, 660.3165723476203, 32173.67826321736, 42576.36135457967, 43960.34059489102, 44047.11905761891, 44060.0, 43595.78202108985, 43739.56260437395, 43848.34227486582, 43956.340654890126, 43987.78006109965, 7835.451518393715, 8791.296696264284, 8943.418677785956, 8956.820863582725, 10232.334898231627, 10036.0, 10435.06084452399, 10437.58249670016, 10439.895601043969, 10455.790884182312, 10458.738531536696, 10487.73780655482, 10492.842607360857, 10497.475126243695, 10501.10740587051, 8026.759197224122, 8202.58987050651, 10344.741381465448, 10395.740106497322, 10609.681709548726, 10401.739956501147, 10464.267501274913, 10477.790444191074, 10491.527881245285, 10496.265261431703, 40976.0, 41190.176196476204, 41281.587184128075, 41316.58683413157, 41321.793391033156, 3588.9282214355826, 3599.4420864766003, 3648.72634552407, 3702.148505843671, 3847.6537111659804, 31466.21334466634, 31731.36537269265, 31965.0, 32075.679243207735, 32343.0, 4080.5715399882884, 4099.3031184698675, 4128.483939507561, 4285.400043993844, 4300.6989510734265, 323315.0, 325627.7437225621, 327110.0, 327138.9999999976, 327150.0, 35205.29589408197, 36051.278974420886, 55782.72108639431, 56720.43279567234, 56889.0, 36921.44617830735, 37116.0, 37169.81415092907, 37229.81385093057, 37244.0, 15647.68704625901, 15836.841631583768, 15933.601659958362, 15952.92023539898, 15975.920120399323, 11201.0, 13251.933740331237, 13403.865961340456, 13521.729565408637, 13882.652933676536, 8588.312934965188, 8618.525981071101, 8626.094260102724, 8668.566571671392, 8689.435186712843, 3307.8842240521467, 3316.5688460499982, 3342.9665703342657, 3345.2974875276464, 3368.3768502827056, 1055.4511653939908, 1076.3649446826253, 1086.8043752124693, 1105.5190991918541, 1219.621917205669, 31844.36311273808, 32431.351372972407, 32860.83569582184, 33089.66910330866, 34295.4855677165, 1154.4054811771944, 1159.513004538096, 1172.9589464368705, 1223.5656341998463, 1290.6837824733, 17138.40015599448, 17155.828441715672, 17169.399071032454, 17190.570235744206, 17193.65612687739, 401191.0, 402163.0, 403405.0, 405135.0, 407212.0, 55270.17094743582, 55483.445165548634, 55660.0, 55727.72136139374, 55780.442195577525, 1308.064733715398, 1413.639521921924, 1429.2567864710293, 1442.5095467541087, 1459.0443259664723, 31424.214394640316, 32205.838970804994, 32792.34415311728, 33122.0, 33378.83310583432, 48013.759931201515, 48316.51683483261, 48486.5151348482, 48544.271835923195, 48680.5131948676, 349265.0, 352549.0, 352712.0, 353153.0, 353990.0, 25296.873515632305, 37828.62171378251, 38753.418698718975, 39243.607563925136, 39392.40911386274, 51795.48204518057, 52493.7375313121, 52839.0, 56038.71980640071, 56565.151522726366, 40274.59725402708, 41779.0, 42383.0, 42446.9388463705, 46428.767856160506, 42041.0, 42296.0, 42663.57336426596, 43094.56905430905, 43624.56375436332, 41973.160536788484, 42688.35967460553, 43056.78471607622, 43303.566964329955, 43316.78341608272, 35691.64308356883, 37250.24993658416, 37681.43477847889, 37720.2455950874, 38460.61539384571, 28729.712702872705, 29141.562876556443, 29224.707752922197, 29262.853685731436, 29438.705612943595], "value": 19249.986791984687, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.202055990818, 410.6181251436164, 412.50087394252955, 417.7994562609947, 427.4656679151061, 12890.871091289084, 17362.65274694507, 18855.434336969895, 18870.905645471772, 19508.51228719283, 13712.286228398541, 18024.27902883883, 18757.531061723465, 19131.0, 20046.59906801863, 5586.832395028146, 5790.797322093727, 5803.680797556132, 5805.0, 5815.302163740357, 5708.08670612702, 5782.219400380958, 5788.71056447178, 5788.913166302509, 5794.913076303858, 4062.4718786557705, 5986.371430999748, 6314.052892066181, 6317.33667964864, 6426.0, 6331.936680633226, 6361.173047503818, 6369.8089057328125, 6387.0100134479, 6395.712192951281, 5537.307836520435, 5647.37878833328, 5653.378128405872, 5681.857953551153, 5686.715664216773, 22667.77332226673, 22684.432889177737, 22778.88610556953, 23235.070597176094, 23461.178858740033, 2659.8936042558275, 2729.0, 2932.0, 2942.0585412667956, 3117.236277112122, 13628.591142265748, 14435.92782036083, 14638.268086595628, 15007.249637518189, 15087.698246035017, 1934.487360849376, 1954.0, 1970.1331414177716, 1996.19154242532, 2009.5980803839145, 83169.58415207885, 86359.56820215858, 87042.56478717565, 87271.12728872757, 87660.56169719175, 10315.008647492294, 10441.269111162297, 10715.83926241107, 10956.0, 10978.176636752276, 15354.0, 15526.913116081765, 15527.534173974798, 15532.301046452936, 15562.299696513684, 8068.919310806934, 8197.590120494071, 8259.141693119, 8274.751757447166, 8300.252977232078, 7950.204979502119, 8148.592570371457, 8525.403221774417, 8571.742847714579, 8776.254018408506, 5741.88516229673, 6111.908321375183, 6504.447121994589, 6694.59832410057, 6748.865022699519, 1840.5674666453372, 1922.5385907382129, 1925.3261358524405, 1976.6046790641929, 1982.0783335748945, 1006.7986402719339, 1026.8026423453814, 1047.8690163729534, 1094.2832444748703, 1148.0471208896595, 18654.0, 19078.52303692391, 19532.92568908695, 19661.803381966285, 19927.0, 1079.293063043692, 1081.1891081688734, 1114.509615769059, 1142.2860712054965, 1172.5661505243115, 11171.608993685182, 11463.082953363712, 11569.6529104127, 11622.825657615143, 11647.59233426826, 480872.0, 502512.0, 508367.0, 508544.0, 511146.0, 50505.494945050814, 55972.72013639906, 76940.0, 83432.0, 85234.0, 1067.69570672358, 1075.5482697267166, 1076.682378698286, 1093.6718984304678, 1099.8295264234057, 17240.448359647693, 17299.481015569552, 17329.133543322532, 17408.73886891723, 17678.292868285123, 37593.62406376011, 38077.0, 38697.61302387053, 38990.4151437723, 39809.60190398059, 49554.504454954986, 50976.74511627418, 51007.48992510027, 51056.48943510666, 51111.0, 8769.246150769744, 8855.91144088551, 8907.658104728274, 9046.276297896022, 9115.544222788967, 12416.0, 12797.42411591462, 13364.398602063126, 13787.862121378657, 13792.241426721626, 11736.295822250693, 12043.33761643083, 12162.513499460276, 12171.0, 12271.263724176579, 11214.719632009264, 11376.715582110513, 11661.183717140058, 11848.763024739284, 11959.521619135137, 11704.70738231517, 12136.0, 12277.534945474674, 12590.874091259337, 12628.179168354027, 8930.374873758774, 9003.099690030944, 9028.09719028092, 9048.909510904807, 9141.77145571366, 8893.599788009684, 11231.157663175458, 11745.706357341134, 11787.587434439753, 11927.522899084286], "value": 9483.014577411857, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.5091367107834, 1542.1749364090215, 1549.8527639874214, 1732.5408766676833, 1862.9254829806816, 8326.542040187787, 8736.694215702444, 8747.956260218698, 8947.86578201326, 8955.910440895588, 8665.870011949826, 8835.690750823722, 8838.734837954871, 8863.246624036965, 8867.733967980956, 2095.8742475451522, 2362.893669784864, 2381.785639292459, 2424.769646883552, 2441.035790862617, 2462.2367066209445, 2480.6775119234476, 2481.689788776403, 2493.3891196656846, 2494.0148641286683, 2435.050330371148, 2458.2379462366725, 2462.495188486356, 2463.4949835283815, 2463.519613675334, 2057.5884823035453, 2338.088145623208, 2423.3941514621256, 2423.903043878243, 2434.8782560871887, 2345.542619189259, 2347.507023525062, 2353.3998830298337, 2353.5292941411617, 2376.4652953085592, 8880.73357799267, 8911.15344042315, 8966.417182883091, 8975.326850486239, 8979.820403591892, 5413.323334583177, 5490.094134467808, 5494.36814766299, 5511.669299842022, 5515.172724091378, 3426.9485957710654, 3431.571053618298, 3502.824858757052, 3570.767900086512, 3583.964160358389, 1117.6926345255038, 1121.7644294698123, 1144.5021415684205, 1152.7262375075588, 1203.0736333023524, 12681.112322137455, 12718.364081795966, 12788.296643684595, 12813.423395947204, 12829.0, 4603.286490593963, 4635.907281854344, 4731.455882573479, 4736.8578942631775, 4754.191787396151, 9858.753531161778, 9871.40771553709, 9910.405375677337, 9929.900700992897, 9945.950270248602, 4739.810407583727, 5326.893462130735, 5332.440093790174, 5395.082835917903, 5429.701366424805, 4937.654364194544, 5044.268581055726, 5044.924326135111, 5090.796368145307, 5112.0031593839585, 3785.091578021255, 3828.3874580067613, 3835.808209589565, 3918.882433526942, 3974.191742757039, 1104.3539529375405, 1114.0, 1123.163243383668, 1142.605800998654, 1146.6297774159798, 910.886139232596, 1022.8977102289712, 1046.2310202001522, 1050.76883085721, 1069.4064794039227, 6241.687915604201, 6261.8121456355475, 6332.620042797447, 6339.175907132136, 6353.5234857385885, 466.54977946282, 478.547772355128, 481.40065618305437, 490.4776413120016, 492.0822665728391, 4237.237297286456, 4312.223799716018, 4350.108227813291, 4351.521332653406, 4353.824467393849, 11095.223334366678, 11509.76980460386, 11602.07183425324, 11607.883921160848, 11617.419129043514, 11240.550377984953, 11333.489992950337, 11359.375234362024, 11386.202965792481, 11426.714332141762, 8003.83992320162, 8303.252707256255, 8484.872726909101, 8562.529060901586, 8577.0, 6036.517078633611, 6124.0, 6203.7828675996125, 6234.1583886174785, 6267.059941008885, 10307.742306442398, 10402.895971040192, 10522.158227341948, 10529.57881684724, 10532.315399499008, 9929.602815887572, 10180.389176649425, 10203.081722644998, 10329.328593641389, 10705.785884282426, 6930.965345173242, 7615.390768738598, 7814.843703126019, 7930.524168549906, 8122.065962414398, 8737.344699147463, 8739.257163141205, 8780.121987801169, 8804.647814087364, 8819.0, 7497.775066748006, 7614.543127412373, 7740.961295193488, 7775.455718099679, 8077.434579579608, 7660.0, 7899.289063984269, 8072.031356237289, 8186.181381861767, 8201.22088401601, 6953.304669533006, 6993.300669932966, 7048.541844780073, 7180.60506672138, 7186.928130718626, 5366.597505187205, 5393.298871146726, 5439.673619582838, 5586.273784408001, 5590.748416321194, 5132.255822905732, 5250.474952504719, 5271.051210781942, 5323.1216849219445, 5339.759710812945], "value": 4652.801933475355, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.57892213655066, 342.2214462098725, 342.717258261934, 349.90727457223824, 352.6121266606734, 5284.682919024856, 5302.416734159244, 5305.018571564254, 5307.734613269336, 5325.295905310302, 5270.710110943896, 5288.550473209782, 5297.841064768054, 5322.467753224676, 5346.946530534694, 3964.5440774311037, 3989.5810939851335, 4109.383592461125, 4134.255833949888, 4145.55412306739, 4087.6116768907054, 4098.7130900836955, 4106.117184805266, 4107.17856428714, 4121.608447197526, 3932.351162058257, 4077.938830917524, 4098.8565400211, 4113.588641135892, 4125.566815484361, 4026.0136266614722, 4108.671306295504, 4136.758972308296, 4136.875893723193, 4137.793110344471, 3670.7614005089586, 3685.963140368589, 3691.446283057536, 3694.390425579803, 3708.03591066322, 5100.974495127538, 5103.2855400244, 5104.821331253425, 5110.718910459923, 5151.021305951857, 4575.702579332333, 4709.882252943669, 4739.170645137122, 4765.880852978668, 4779.713217206944, 5386.676799392049, 5414.296141501619, 5431.728413579305, 5463.235147079412, 5482.890342193174, 4052.4326594276827, 4059.756414615103, 4071.4299998000006, 4072.8370865165357, 4084.101497670508, 5568.443155684439, 5583.860403489945, 5666.235058267122, 5711.228984087136, 5736.36513593628, 4151.813168407429, 4166.791660417028, 4170.311898536768, 4175.582441755861, 4178.791060446965, 4273.465816772903, 4279.593438623326, 4286.507051689034, 4286.678499112579, 4287.142571485653, 4672.976635116803, 4686.297055441711, 4798.184308667535, 4809.326694262806, 4815.686980346266, 5441.0684207105905, 5500.367457742331, 5513.0, 5530.5022547970875, 5576.358718747315, 1421.175718083509, 1428.5857101440465, 1435.4617018618019, 1440.279860069965, 1451.2235953764896, 917.9862302065475, 918.9816203676023, 982.9901700983041, 996.6860438961704, 1009.2027298434155, 5109.259157422153, 5191.6884986900905, 5211.557017653542, 5250.658707184097, 5250.901967838993, 5400.567954563626, 5460.399356070829, 5496.7526461308535, 5510.393856675762, 5517.751701173376, 5087.135187018216, 5143.871403214875, 5155.149400348975, 5180.844574662766, 5494.340679118452, 2903.259668784489, 2924.596193826975, 2972.390659914712, 2992.58103865459, 3005.7745669074907, 5631.352394474606, 5692.6299790513485, 5702.54379649627, 5748.597598168088, 5753.33836608787, 5471.659443436383, 5612.354579223442, 5638.379778224392, 5655.80204692842, 5660.320761508646, 5694.231278777312, 5714.7428365723645, 5743.310802703619, 5786.247787787561, 5797.826065218051, 5284.656497327662, 5523.944760552343, 5537.473939975695, 5586.86032849182, 5589.972050139886, 5491.588130890119, 5500.0, 5501.669899806025, 5519.724013799214, 5534.889302214014, 5704.429557044263, 5745.6552606843725, 5767.9134812977, 5806.506446952057, 5844.766209351748, 3827.8660246891227, 3990.8802735916806, 4003.9399409008306, 4102.425660407485, 4122.8456032311005, 5659.962413531043, 5712.314522257356, 5729.570282228933, 5780.063678667693, 5786.161006653927, 5345.54562862161, 5434.456554344534, 5581.051221292309, 5609.775608975595, 5620.971895140498, 5562.749676264497, 5583.748731307182, 5604.159376093456, 5610.270664813548, 5611.352620796351, 5310.867228319323, 5505.559555235652, 5513.82954800983, 5516.365617953827, 5528.502434780889, 5582.330120385417, 5602.607817452739, 5608.186812912187, 5671.801486947937, 5694.402087780723, 5510.586705997146, 5534.280543529316, 5546.530169505004, 5548.196836028387, 5550.306211723535], "value": 4224.378910563323, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.0931410250007, 780.9960950195249, 874.3529787956913, 883.8939327280725, 891.4428482198626, 13880.305984700763, 14459.493917712882, 14465.20441375724, 14494.0, 14513.49202777903, 13321.733565328688, 13366.66583335417, 13366.866331336709, 13371.331433428313, 13373.264470454122, 679.6533767778432, 779.3998621061779, 805.2269820971874, 806.0609390060572, 807.4267270238138, 797.1948332184506, 799.6081919859286, 800.6437135474716, 802.3741481644298, 803.3813963248296, 711.9679614417364, 850.0776657326777, 850.2985037344184, 850.2985037344184, 860.6686425726109, 835.1564919431357, 855.4396870050127, 856.9442986205908, 857.9227869491775, 858.8668756342714, 638.0811631250983, 639.1467391032977, 639.1563136659611, 639.5523133806354, 639.6897504710212, 12392.194507357082, 12427.813582796265, 12433.191842530206, 12453.937730311382, 12464.0, 12801.743965120646, 12954.157979731288, 13186.736265274641, 13455.327233638376, 13492.920566354667, 11376.488058037408, 11388.60139895108, 11726.120540959462, 11978.580749673807, 12041.578544750979, 4364.759938203398, 4375.956240437618, 4386.890327741832, 4455.376247325377, 4508.864734057984, 52078.0, 52723.945521089365, 53775.0, 53959.730201349135, 54090.72954635201, 9073.274138068939, 9173.633054677872, 10679.09227715653, 10799.190060745475, 10922.1808364373, 19305.8069419304, 19402.902985484983, 19506.512337191685, 19513.609727805364, 19516.0, 7599.316061554487, 7602.619869006528, 7822.726204582926, 7863.921360786433, 8003.719869804529, 4440.378347031418, 4512.571305725951, 4512.661550383735, 4603.5396460353695, 4646.651501137429, 3343.1976325681662, 3421.298633780069, 3423.17843717506, 3433.176037750972, 3435.4159792835276, 621.1862460177182, 630.9779157729549, 653.3792896748014, 666.9699863506154, 716.1012928774459, 644.4103645164715, 653.0856800479366, 745.22124380023, 796.9322607578305, 801.1067659559666, 9251.814963700688, 9260.7684807879, 9357.719268421959, 9389.953050234843, 9627.422354658604, 635.3868516881232, 636.8153235561728, 705.9152901651732, 760.7413479417024, 791.2562191539934, 4370.453693288339, 4564.6348292136545, 4576.931346029813, 4580.450345958439, 4677.391939047902, 62330.688346557974, 63465.922726181794, 63626.36373636297, 63717.68141159357, 63776.36223637704, 56325.7183714087, 56636.15045774317, 56639.71680141655, 57813.4218657808, 59435.0, 52407.0, 52947.73526132345, 53139.46860531423, 53929.73035134799, 54311.18533222005, 8623.784405389664, 8703.347248956226, 8741.519216443157, 8768.298536116969, 8836.779080523038, 34061.31877362489, 34373.312533748685, 34461.13847153841, 34656.0, 36393.45409818802, 43884.12231755283, 45759.54240457553, 46162.0, 46289.3056604158, 46909.530904691885, 11051.613193538187, 11077.169212308949, 11107.44462776874, 11212.215144940103, 11413.885861141282, 12530.686732831753, 12557.811632825698, 12795.296258705861, 12986.155899866479, 13250.337483125997, 12405.689857753629, 12716.618501444971, 12926.935365323114, 12937.159084659466, 13145.53990610324, 11636.29498618852, 11753.588624398106, 11817.290962542274, 11915.0, 12480.687982800502, 11362.94318528402, 11477.368744718786, 11756.94121529387, 11816.881831181578, 12120.27278363301, 9260.953695231481, 9458.054194580762, 9618.903810961801, 9714.80570388574, 10170.440625765654, 9706.854397183906, 9955.20358371343, 9997.750056248651, 10087.142592879687, 10823.837642435212], "value": 6673.789083181075, "unit": "score"}], "name": "dromaeo_css", "value": 7109.597393902495, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 109.0, 112.0, 111.0, 111.0, 109.0, 111.0, 111.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 153.0, 225.0, 132.0, 152.0, 154.0, 134.0, 195.0, 128.0], "value": 155.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 159.0, 162.0, 162.0, 161.0, 161.0, 161.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 150.0, 76.0, 75.0, 77.0, 75.0, 130.0, 76.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 150.0, 91.0, 97.0, 92.0, 121.0, 93.0, 93.0, 126.0], "value": 104.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 58.0, 58.0, 59.0, 59.0, 109.0, 55.0, 56.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 81.0, 79.0, 80.0, 81.0, 80.0, 80.0, 81.0, 80.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 124.0, 126.0, 123.0, 124.0, 124.0, 126.0, 126.0, 190.0, 125.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 169.0, 177.0, 172.0, 169.0, 173.0, 169.0, 167.0, 168.0, 168.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 68.0, 64.0, 68.0, 65.0, 66.0, 65.0, 63.0, 64.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1548.8, "alertThreshold": 2.0}]} 07:30:01 INFO - Return code: 0 07:30:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:30:02 ERROR - # TBPL SUCCESS # 07:30:02 INFO - Running post-action listener: _resource_record_post_action 07:30:02 INFO - ##### 07:30:02 INFO - ##### Finished run-tests step (success) 07:30:02 INFO - ##### 07:30:02 INFO - Running post-run listener: _resource_record_post_run 07:30:03 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 543285248; Read time: 8; Write time: 1216080 07:30:03 INFO - install - Wall time: 18s; CPU: 17.0%; Read bytes: 0; Write bytes: 254103552; Read time: 0; Write time: 741704 07:30:03 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289181696; Read time: 8; Write time: 474376 07:30:03 INFO - Running post-run listener: _upload_blobber_files 07:30:03 INFO - Blob upload gear active. 07:30:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:30:03 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 07:30:03 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:30:03 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:30:03 INFO - (blobuploader) - INFO - Open directory for files ... 07:30:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:30:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:30:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:04 INFO - (blobuploader) - INFO - Done attempting. 07:30:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:30:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:30:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:30:05 INFO - (blobuploader) - INFO - Done attempting. 07:30:05 INFO - (blobuploader) - INFO - Iteration through files over. 07:30:05 INFO - Return code: 0 07:30:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:30:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:30:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/875b516eed212c5cba2893e2e4aa8b2c261e5af6c65bd7cda5371389b8d9d8af014c1d1f500867f10f82fffd2a521a25b548603888a148b9727fcc132389a62f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f32fbca84ff26b5809baf8b9e5a55409172fb41baf6e061155876095c2ec196ff1360c3d5ef511c4adf07155843f8384aa1f4516b7ac675dcc735cf9d5e8863"} 07:30:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:30:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:30:05 INFO - Contents: 07:30:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/875b516eed212c5cba2893e2e4aa8b2c261e5af6c65bd7cda5371389b8d9d8af014c1d1f500867f10f82fffd2a521a25b548603888a148b9727fcc132389a62f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f32fbca84ff26b5809baf8b9e5a55409172fb41baf6e061155876095c2ec196ff1360c3d5ef511c4adf07155843f8384aa1f4516b7ac675dcc735cf9d5e8863"} 07:30:05 INFO - Running post-run listener: copy_logs_to_upload_dir 07:30:05 INFO - Copying logs to upload dir... 07:30:05 INFO - mkdir: /builds/slave/test/build/upload/logs 07:30:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.286103 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-09 07:30:04.910008) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-09 07:30:04.915122) ========= 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=33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/875b516eed212c5cba2893e2e4aa8b2c261e5af6c65bd7cda5371389b8d9d8af014c1d1f500867f10f82fffd2a521a25b548603888a148b9727fcc132389a62f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f32fbca84ff26b5809baf8b9e5a55409172fb41baf6e061155876095c2ec196ff1360c3d5ef511c4adf07155843f8384aa1f4516b7ac675dcc735cf9d5e8863"} build_url:https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/Dcgsn4kMSsGj5RXRx8KMTQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/875b516eed212c5cba2893e2e4aa8b2c261e5af6c65bd7cda5371389b8d9d8af014c1d1f500867f10f82fffd2a521a25b548603888a148b9727fcc132389a62f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f32fbca84ff26b5809baf8b9e5a55409172fb41baf6e061155876095c2ec196ff1360c3d5ef511c4adf07155843f8384aa1f4516b7ac675dcc735cf9d5e8863"}' ========= master_lag: 1.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-09 07:30:06.067987) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:30:06.068674) ========= 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=33d5923976266a1c518c4bf7000001ab-1462802877.231240-164717685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:30:06.089328) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 07:30:06.089910) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 07:30:06.090457) ========= ========= Total master_lag: 1.24 =========