builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1465280245.44 results: success (0) buildid: 20160606203027 builduid: 1a213dc7dff44e669decd9e6e96a8bca revision: 580436387d62e0a40fcc781832c3291858121816 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.446008) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.446437) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.446767) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.487434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.487735) ========= 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=0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 23:17:25-- 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% 71.2M=0s 2016-06-06 23:17:26 (71.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115020 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.616845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.617155) ========= 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=0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018284 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.667132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:25.667476) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 580436387d62e0a40fcc781832c3291858121816 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 580436387d62e0a40fcc781832c3291858121816 --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=0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 23:17:26,137 truncating revision to first 12 chars 2016-06-06 23:17:26,137 Setting DEBUG logging. 2016-06-06 23:17:26,137 attempt 1/10 2016-06-06 23:17:26,137 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/580436387d62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 23:17:26,588 unpacking tar archive at: fx-team-580436387d62/testing/mozharness/ program finished with exit code 0 elapsedTime=0.786099 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:26.467532) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:26.467899) ========= script_repo_revision: 580436387d62e0a40fcc781832c3291858121816 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:26.468344) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:26.468638) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 23:17:26.477608) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 17 secs) (at 2016-06-06 23:17:26.477892) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:17:27 INFO - MultiFileLogger online at 20160606 23:17:27 in /builds/slave/test 23:17:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:17:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:17:27 INFO - {'append_to_log': False, 23:17:27 INFO - 'base_work_dir': '/builds/slave/test', 23:17:27 INFO - 'blob_upload_branch': 'Fx-Team', 23:17:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:17:27 INFO - 'branch': 'Fx-Team', 23:17:27 INFO - 'buildbot_json_path': 'buildprops.json', 23:17:27 INFO - 'config_files': ('talos/linux_config.py',), 23:17:27 INFO - 'default_actions': ('clobber', 23:17:27 INFO - 'read-buildbot-config', 23:17:27 INFO - 'download-and-extract', 23:17:27 INFO - 'populate-webroot', 23:17:27 INFO - 'create-virtualenv', 23:17:27 INFO - 'install', 23:17:27 INFO - 'run-tests'), 23:17:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:17:27 INFO - 'download_minidump_stackwalk': True, 23:17:27 INFO - 'download_symbols': 'ondemand', 23:17:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:17:27 INFO - 'tooltool.py': '/tools/tooltool.py', 23:17:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:17:27 INFO - '/tools/misc-python/virtualenv.py')}, 23:17:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:17:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:17:27 INFO - 'installer_path': 'installer.exe', 23:17:27 INFO - 'log_level': 'info', 23:17:27 INFO - 'log_name': 'talos', 23:17:27 INFO - 'log_to_console': True, 23:17:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:17:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:17:27 INFO - 'opt_config_files': (), 23:17:27 INFO - 'pip_index': False, 23:17:27 INFO - 'sps_profile': False, 23:17:27 INFO - 'sps_profile_interval': 0, 23:17:27 INFO - 'suite': 'dromaeojs', 23:17:27 INFO - 'system_bits': '32', 23:17:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:17:27 INFO - 'title': 'talos-linux64-ix-017', 23:17:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:17:27 INFO - 'use_talos_json': True, 23:17:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:17:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:17:27 INFO - 'work_dir': 'build'} 23:17:27 INFO - ##### 23:17:27 INFO - ##### Running clobber step. 23:17:27 INFO - ##### 23:17:27 INFO - Running pre-action listener: _resource_record_pre_action 23:17:27 INFO - Running main action method: clobber 23:17:27 INFO - rmtree: /builds/slave/test/build 23:17:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:17:27 INFO - Running post-action listener: _resource_record_post_action 23:17:27 INFO - ##### 23:17:27 INFO - ##### Finished clobber step (success) 23:17:27 INFO - ##### 23:17:27 INFO - ##### 23:17:27 INFO - ##### Running read-buildbot-config step. 23:17:27 INFO - ##### 23:17:27 INFO - Running pre-action listener: _resource_record_pre_action 23:17:27 INFO - Running main action method: read_buildbot_config 23:17:27 INFO - Using buildbot properties: 23:17:27 INFO - { 23:17:27 INFO - "project": "", 23:17:27 INFO - "product": "firefox", 23:17:27 INFO - "script_repo_revision": "production", 23:17:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:17:27 INFO - "repository": "", 23:17:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:17:27 INFO - "buildid": "20160606203027", 23:17:27 INFO - "pgo_build": "True", 23:17:27 INFO - "basedir": "/builds/slave/test", 23:17:27 INFO - "buildnumber": 166, 23:17:27 INFO - "slavename": "talos-linux64-ix-017", 23:17:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:17:27 INFO - "platform": "ubuntu64_hw", 23:17:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:17:27 INFO - "branch": "fx-team", 23:17:27 INFO - "repo_path": "integration/fx-team", 23:17:27 INFO - "revision": "580436387d62e0a40fcc781832c3291858121816", 23:17:27 INFO - "stage_platform": "linux64-pgo", 23:17:27 INFO - "builduid": "1a213dc7dff44e669decd9e6e96a8bca", 23:17:27 INFO - "slavebuilddir": "test" 23:17:27 INFO - } 23:17:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:17:27 INFO - Running post-action listener: _resource_record_post_action 23:17:27 INFO - ##### 23:17:27 INFO - ##### Finished read-buildbot-config step (success) 23:17:27 INFO - ##### 23:17:27 INFO - ##### 23:17:27 INFO - ##### Running download-and-extract step. 23:17:27 INFO - ##### 23:17:27 INFO - Running pre-action listener: _resource_record_pre_action 23:17:27 INFO - Running main action method: download_and_extract 23:17:27 INFO - mkdir: /builds/slave/test/build/tests 23:17: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')]} 23:17:27 INFO - https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:17:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:17:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:17:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:17:30 INFO - Downloaded 1361 bytes. 23:17:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:17:30 INFO - Using the following test package requirements: 23:17:30 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:30 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:17:30 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:17:30 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'jsshell-linux-x86_64.zip'], 23:17:30 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:17:30 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:17:30 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:17:30 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:17:30 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:17:30 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:17:30 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:17:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:17:30 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')]} 23:17:30 INFO - https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:17:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:17:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:17:31 INFO - Downloaded 24443563 bytes. 23:17:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:17:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:17:33 INFO - Return code: 0 23:17:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:17:33 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')]} 23:17:33 INFO - https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:17:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:17:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:17:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:17:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:17:34 INFO - Downloaded 24443563 bytes. 23:17:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:17:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:17:35 INFO - Return code: 0 23:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:17:35 INFO - https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:17:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:17:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:17:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:17:37 INFO - Downloaded 10833369 bytes. 23:17:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:17:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:17:37 INFO - Return code: 0 23:17: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')]} 23:17:37 INFO - https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:17:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:17:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:17:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:17:38 INFO - Downloaded 59195570 bytes. 23:17:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:17:38 INFO - mkdir: /builds/slave/test/properties 23:17:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:17:38 INFO - Writing to file /builds/slave/test/properties/build_url 23:17:38 INFO - Contents: 23:17:38 INFO - build_url:https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:17:39 INFO - Running post-action listener: _resource_record_post_action 23:17:39 INFO - Running post-action listener: set_extra_try_arguments 23:17:39 INFO - ##### 23:17:39 INFO - ##### Finished download-and-extract step (success) 23:17:39 INFO - ##### 23:17:39 INFO - ##### 23:17:39 INFO - ##### Running populate-webroot step. 23:17:39 INFO - ##### 23:17:39 INFO - Running pre-action listener: _resource_record_pre_action 23:17:39 INFO - Running main action method: populate_webroot 23:17:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:17:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:17:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:17:39 INFO - '--tppagecycles', 23:17:39 INFO - '7'], 23:17:39 INFO - 'tests': ['tsvgm']}}, 23:17:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:17:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:17:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:17:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:17:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['damp', 'tps']}, 23:17:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'tests': ['damp', 'tps']}, 23:17:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['dromaeo_dom']}, 23:17:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:17:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['a11yr', 23:17:39 INFO - 'ts_paint', 23:17:39 INFO - 'tpaint', 23:17:39 INFO - 'sessionrestore', 23:17:39 INFO - 'sessionrestore_no_auto_restore', 23:17:39 INFO - 'tabpaint']}, 23:17:39 INFO - 'other-e10s': {'tests': ['a11yr', 23:17:39 INFO - 'ts_paint', 23:17:39 INFO - 'tpaint', 23:17:39 INFO - 'sessionrestore', 23:17:39 INFO - 'sessionrestore_no_auto_restore', 23:17:39 INFO - 'tabpaint']}, 23:17:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:17:39 INFO - 'ts_paint', 23:17:39 INFO - 'tpaint', 23:17:39 INFO - 'sessionrestore', 23:17:39 INFO - 'sessionrestore_no_auto_restore', 23:17:39 INFO - 'tabpaint']}, 23:17:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:17:39 INFO - 'ts_paint', 23:17:39 INFO - 'tpaint', 23:17:39 INFO - 'sessionrestore', 23:17:39 INFO - 'sessionrestore_no_auto_restore', 23:17:39 INFO - 'tabpaint']}, 23:17:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['a11yr', 23:17:39 INFO - 'ts_paint', 23:17:39 INFO - 'tpaint', 23:17:39 INFO - 'sessionrestore', 23:17:39 INFO - 'sessionrestore_no_auto_restore', 23:17:39 INFO - 'tabpaint']}, 23:17:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['a11yr', 23:17:39 INFO - 'ts_paint', 23:17:39 INFO - 'tpaint', 23:17:39 INFO - 'sessionrestore', 23:17:39 INFO - 'sessionrestore_no_auto_restore', 23:17:39 INFO - 'tabpaint']}, 23:17:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['tsvgx', 23:17:39 INFO - 'tsvgr_opacity', 23:17:39 INFO - 'tart', 23:17:39 INFO - 'tscrollx', 23:17:39 INFO - 'cart']}, 23:17:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:17:39 INFO - 'tsvgr_opacity', 23:17:39 INFO - 'tart', 23:17:39 INFO - 'tscrollx', 23:17:39 INFO - 'cart']}, 23:17:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'talos_options': ['--disable-e10s'], 23:17:39 INFO - 'tests': ['tp5o']}, 23:17:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'tests': ['tp5o']}, 23:17:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'talos_options': ['--disable-e10s', 23:17:39 INFO - '--xperf_path', 23:17:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:39 INFO - 'tests': ['tp5n']}, 23:17:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:39 INFO - 'talos_options': ['--xperf_path', 23:17:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:39 INFO - 'tests': ['tp5n']}}, 23:17:39 INFO - 'talos.zip': {'path': '', 23:17:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:17:39 INFO - Running post-action listener: _resource_record_post_action 23:17:39 INFO - ##### 23:17:39 INFO - ##### Finished populate-webroot step (success) 23:17:39 INFO - ##### 23:17:39 INFO - ##### 23:17:39 INFO - ##### Running create-virtualenv step. 23:17:39 INFO - ##### 23:17:39 INFO - Running pre-action listener: _resource_record_pre_action 23:17:39 INFO - Running main action method: create_virtualenv 23:17:39 INFO - Creating virtualenv /builds/slave/test/build/venv 23:17:39 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 23:17:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:17:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:17:39 INFO - Using real prefix '/usr' 23:17:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:17:40 INFO - Installing distribute.............................................................................................................................................................................................done. 23:17:41 INFO - Installing pip.................done. 23:17:42 INFO - Return code: 0 23:17:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:17:42 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')]} 23:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:42 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')]} 23:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:42 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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17:42 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 23:17:42 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 23:17:42 INFO - Using env: {'DISPLAY': ':0', 23:17:42 INFO - 'HOME': '/home/cltbld', 23:17:42 INFO - 'LANG': 'en_US.UTF-8', 23:17:42 INFO - 'LANGUAGE': 'en_US:en', 23:17:42 INFO - 'LOGNAME': 'cltbld', 23:17:42 INFO - 'MAIL': '/var/mail/cltbld', 23:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:42 INFO - 'MOZ_NO_REMOTE': '1', 23:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:42 INFO - 'NO_EM_RESTART': '1', 23:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:42 INFO - 'PWD': '/builds/slave/test', 23:17:42 INFO - 'SHELL': '/bin/bash', 23:17:42 INFO - 'SHLVL': '1', 23:17:42 INFO - 'TERM': 'linux', 23:17:42 INFO - 'TMOUT': '86400', 23:17:42 INFO - 'USER': 'cltbld', 23:17:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:42 INFO - '_': '/tools/buildbot/bin/python'} 23:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:42 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 23:17:42 INFO - Cleaning up... 23:17:42 INFO - Return code: 0 23:17:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:17:42 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')]} 23:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:42 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')]} 23:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:17:42 INFO - Using env: {'DISPLAY': ':0', 23:17:42 INFO - 'HOME': '/home/cltbld', 23:17:42 INFO - 'LANG': 'en_US.UTF-8', 23:17:42 INFO - 'LANGUAGE': 'en_US:en', 23:17:42 INFO - 'LOGNAME': 'cltbld', 23:17:42 INFO - 'MAIL': '/var/mail/cltbld', 23:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:42 INFO - 'MOZ_NO_REMOTE': '1', 23:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:42 INFO - 'NO_EM_RESTART': '1', 23:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:42 INFO - 'PWD': '/builds/slave/test', 23:17:42 INFO - 'SHELL': '/bin/bash', 23:17:42 INFO - 'SHLVL': '1', 23:17:42 INFO - 'TERM': 'linux', 23:17:42 INFO - 'TMOUT': '86400', 23:17:42 INFO - 'USER': 'cltbld', 23:17:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:42 INFO - '_': '/tools/buildbot/bin/python'} 23:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:42 INFO - Downloading/unpacking psutil>=3.1.1 23:17:42 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 23:17:42 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 23:17:42 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 23:17:42 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 23:17:42 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 23:17:42 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 23:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:17:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:17:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:17:44 INFO - Installing collected packages: psutil 23:17:44 INFO - Running setup.py install for psutil 23:17:44 INFO - building 'psutil._psutil_linux' extension 23:17:44 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 23:17:44 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 23:17:44 INFO - building 'psutil._psutil_posix' extension 23:17:44 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 23:17:44 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 23:17:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:17:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:17:44 INFO - Successfully installed psutil 23:17:44 INFO - Cleaning up... 23:17:44 INFO - Return code: 0 23:17:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:17: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 mozsystemmonitor==0.3 23:17:44 INFO - Using env: {'DISPLAY': ':0', 23:17:44 INFO - 'HOME': '/home/cltbld', 23:17:44 INFO - 'LANG': 'en_US.UTF-8', 23:17:44 INFO - 'LANGUAGE': 'en_US:en', 23:17:44 INFO - 'LOGNAME': 'cltbld', 23:17:44 INFO - 'MAIL': '/var/mail/cltbld', 23:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:44 INFO - 'MOZ_NO_REMOTE': '1', 23:17:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:44 INFO - 'NO_EM_RESTART': '1', 23:17:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:44 INFO - 'PWD': '/builds/slave/test', 23:17:45 INFO - 'SHELL': '/bin/bash', 23:17:45 INFO - 'SHLVL': '1', 23:17:45 INFO - 'TERM': 'linux', 23:17:45 INFO - 'TMOUT': '86400', 23:17:45 INFO - 'USER': 'cltbld', 23:17:45 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:45 INFO - '_': '/tools/buildbot/bin/python'} 23:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:17:45 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 23:17:45 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 23:17:45 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 23:17:45 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 23:17:45 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 23:17:45 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 23:17:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:17:47 INFO - Installing collected packages: mozsystemmonitor 23:17:47 INFO - Running setup.py install for mozsystemmonitor 23:17:47 INFO - Successfully installed mozsystemmonitor 23:17:47 INFO - Cleaning up... 23:17:47 INFO - Return code: 0 23:17:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:17:47 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')]} 23:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:47 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')]} 23:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:17:47 INFO - Using env: {'DISPLAY': ':0', 23:17:47 INFO - 'HOME': '/home/cltbld', 23:17:47 INFO - 'LANG': 'en_US.UTF-8', 23:17:47 INFO - 'LANGUAGE': 'en_US:en', 23:17:47 INFO - 'LOGNAME': 'cltbld', 23:17:47 INFO - 'MAIL': '/var/mail/cltbld', 23:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:47 INFO - 'MOZ_NO_REMOTE': '1', 23:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:47 INFO - 'NO_EM_RESTART': '1', 23:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:47 INFO - 'PWD': '/builds/slave/test', 23:17:47 INFO - 'SHELL': '/bin/bash', 23:17:47 INFO - 'SHLVL': '1', 23:17:47 INFO - 'TERM': 'linux', 23:17:47 INFO - 'TMOUT': '86400', 23:17:47 INFO - 'USER': 'cltbld', 23:17:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:47 INFO - '_': '/tools/buildbot/bin/python'} 23:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:47 INFO - Downloading/unpacking jsonschema==2.5.1 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:17:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:49 INFO - Downloading functools32-3.2.3-2.tar.gz 23:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:17:49 INFO - warning: no files found matching '*.txt' 23:17:49 INFO - no previously-included directories found matching 'build' 23:17:49 INFO - no previously-included directories found matching 'dist' 23:17:49 INFO - no previously-included directories found matching '.git*' 23:17:49 INFO - Installing collected packages: jsonschema, functools32 23:17:49 INFO - Running setup.py install for functools32 23:17:49 INFO - warning: no files found matching '*.txt' 23:17:49 INFO - no previously-included directories found matching 'build' 23:17:49 INFO - no previously-included directories found matching 'dist' 23:17:49 INFO - no previously-included directories found matching '.git*' 23:17:49 INFO - Successfully installed jsonschema functools32 23:17:49 INFO - Cleaning up... 23:17:49 INFO - Return code: 0 23:17:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17: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 23:17: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 23:17:49 INFO - Using env: {'DISPLAY': ':0', 23:17:49 INFO - 'HOME': '/home/cltbld', 23:17:49 INFO - 'LANG': 'en_US.UTF-8', 23:17:49 INFO - 'LANGUAGE': 'en_US:en', 23:17:49 INFO - 'LOGNAME': 'cltbld', 23:17:49 INFO - 'MAIL': '/var/mail/cltbld', 23:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:49 INFO - 'MOZ_NO_REMOTE': '1', 23:17:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:49 INFO - 'NO_EM_RESTART': '1', 23:17:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:49 INFO - 'PWD': '/builds/slave/test', 23:17:49 INFO - 'SHELL': '/bin/bash', 23:17:49 INFO - 'SHLVL': '1', 23:17:49 INFO - 'TERM': 'linux', 23:17:49 INFO - 'TMOUT': '86400', 23:17:49 INFO - 'USER': 'cltbld', 23:17:49 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:49 INFO - '_': '/tools/buildbot/bin/python'} 23:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:50 INFO - Downloading/unpacking blobuploader==1.2.4 23:17:50 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 23:17:50 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 23:17:50 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 23:17:50 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 23:17:50 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 23:17:50 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 23:17:51 INFO - Downloading blobuploader-1.2.4.tar.gz 23:17:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:17:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:17:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:17:52 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 23:17:52 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 23:17:52 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 23:17:52 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 23:17:52 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 23:17:52 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 23:17:52 INFO - Downloading docopt-0.6.1.tar.gz 23:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:17:52 INFO - Installing collected packages: blobuploader, requests, docopt 23:17:52 INFO - Running setup.py install for blobuploader 23:17:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:17:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:17:52 INFO - Running setup.py install for requests 23:17:53 INFO - Running setup.py install for docopt 23:17:53 INFO - Successfully installed blobuploader requests docopt 23:17:53 INFO - Cleaning up... 23:17:53 INFO - Return code: 0 23:17:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17:53 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')]} 23:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:53 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')]} 23:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:53 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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17:53 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 23:17:53 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 23:17:53 INFO - Using env: {'DISPLAY': ':0', 23:17:53 INFO - 'HOME': '/home/cltbld', 23:17:53 INFO - 'LANG': 'en_US.UTF-8', 23:17:53 INFO - 'LANGUAGE': 'en_US:en', 23:17:53 INFO - 'LOGNAME': 'cltbld', 23:17:53 INFO - 'MAIL': '/var/mail/cltbld', 23:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:53 INFO - 'MOZ_NO_REMOTE': '1', 23:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:53 INFO - 'NO_EM_RESTART': '1', 23:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:53 INFO - 'PWD': '/builds/slave/test', 23:17:53 INFO - 'SHELL': '/bin/bash', 23:17:53 INFO - 'SHLVL': '1', 23:17:53 INFO - 'TERM': 'linux', 23:17:53 INFO - 'TMOUT': '86400', 23:17:53 INFO - 'USER': 'cltbld', 23:17:53 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:53 INFO - '_': '/tools/buildbot/bin/python'} 23:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:53 INFO - Running setup.py (path:/tmp/pip-h9ng3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:53 INFO - Running setup.py (path:/tmp/pip-QygINp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:53 INFO - Running setup.py (path:/tmp/pip-WL_pfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:53 INFO - Running setup.py (path:/tmp/pip-iYQe5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:53 INFO - Running setup.py (path:/tmp/pip-gXCi14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:53 INFO - Running setup.py (path:/tmp/pip-WxSACG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:53 INFO - Running setup.py (path:/tmp/pip-XfuBCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:53 INFO - Running setup.py (path:/tmp/pip-9Sj75M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:54 INFO - Running setup.py (path:/tmp/pip-Js2YJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:54 INFO - Running setup.py (path:/tmp/pip-vxd3X4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:54 INFO - Running setup.py (path:/tmp/pip-adQUhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:54 INFO - Running setup.py (path:/tmp/pip-U04QlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:54 INFO - Running setup.py (path:/tmp/pip-8HbjiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:54 INFO - Running setup.py (path:/tmp/pip-cAigSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:54 INFO - Running setup.py (path:/tmp/pip-C_iXbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:54 INFO - Running setup.py (path:/tmp/pip-W72EZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:54 INFO - Running setup.py (path:/tmp/pip-D_7au_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:17:54 INFO - Running setup.py install for manifestparser 23:17:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:17:54 INFO - Running setup.py install for mozcrash 23:17:54 INFO - Running setup.py install for mozdebug 23:17:54 INFO - Running setup.py install for mozdevice 23:17:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Running setup.py install for mozfile 23:17:55 INFO - Running setup.py install for mozhttpd 23:17:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Running setup.py install for mozinfo 23:17:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Running setup.py install for mozInstall 23:17:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Running setup.py install for mozleak 23:17:55 INFO - Running setup.py install for mozlog 23:17:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Running setup.py install for moznetwork 23:17:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:17:55 INFO - Running setup.py install for mozprocess 23:17:55 INFO - Running setup.py install for mozprofile 23:17:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:17:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:17:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:17:56 INFO - Running setup.py install for mozrunner 23:17:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:17:56 INFO - Running setup.py install for mozscreenshot 23:17:56 INFO - Running setup.py install for moztest 23:17:56 INFO - Running setup.py install for mozversion 23:17:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:17:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:17:56 INFO - Cleaning up... 23:17:56 INFO - Return code: 0 23:17:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:17: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 23:17: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 23:17:56 INFO - Using env: {'DISPLAY': ':0', 23:17:56 INFO - 'HOME': '/home/cltbld', 23:17:56 INFO - 'LANG': 'en_US.UTF-8', 23:17:56 INFO - 'LANGUAGE': 'en_US:en', 23:17:56 INFO - 'LOGNAME': 'cltbld', 23:17:56 INFO - 'MAIL': '/var/mail/cltbld', 23:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:56 INFO - 'MOZ_NO_REMOTE': '1', 23:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:56 INFO - 'NO_EM_RESTART': '1', 23:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:56 INFO - 'PWD': '/builds/slave/test', 23:17:56 INFO - 'SHELL': '/bin/bash', 23:17:56 INFO - 'SHLVL': '1', 23:17:56 INFO - 'TERM': 'linux', 23:17:56 INFO - 'TMOUT': '86400', 23:17:56 INFO - 'USER': 'cltbld', 23:17:56 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:56 INFO - '_': '/tools/buildbot/bin/python'} 23:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:56 INFO - Running setup.py (path:/tmp/pip-i2OQxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17: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)) 23:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:56 INFO - Running setup.py (path:/tmp/pip-0xSXwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17: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)) 23:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:56 INFO - Running setup.py (path:/tmp/pip-zYvhL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17: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)) 23:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:56 INFO - Running setup.py (path:/tmp/pip-1BuOix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17: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)) 23:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:56 INFO - Running setup.py (path:/tmp/pip-9WwXsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:57 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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:57 INFO - Running setup.py (path:/tmp/pip-tXObJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:57 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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:57 INFO - Running setup.py (path:/tmp/pip-7phyVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:57 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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:57 INFO - Running setup.py (path:/tmp/pip-dnS9fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:57 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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:57 INFO - Running setup.py (path:/tmp/pip-W705a4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:57 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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:57 INFO - Running setup.py (path:/tmp/pip-zTnNdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:57 INFO - Running setup.py (path:/tmp/pip-FMOUze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17: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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:57 INFO - Running setup.py (path:/tmp/pip-TA_FEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17: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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:57 INFO - Running setup.py (path:/tmp/pip-vjNWQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17: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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:57 INFO - Running setup.py (path:/tmp/pip-7UInqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17: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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:57 INFO - Running setup.py (path:/tmp/pip-ZdCYkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17: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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:57 INFO - Running setup.py (path:/tmp/pip-tqXQNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17: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)) 23:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:57 INFO - Running setup.py (path:/tmp/pip-MSQg4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17: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)) 23:17: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)) 23:17: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)) 23:17: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)) 23:17: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)) 23:17: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)) 23:17:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:59 INFO - Downloading blessings-1.6.tar.gz 23:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:17:59 INFO - Installing collected packages: blessings 23:17:59 INFO - Running setup.py install for blessings 23:18:00 INFO - Successfully installed blessings 23:18:00 INFO - Cleaning up... 23:18:00 INFO - Return code: 0 23:18:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:18:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:00 INFO - Reading from file tmpfile_stdout 23:18:00 INFO - Current package versions: 23:18:00 INFO - argparse == 1.2.1 23:18:00 INFO - blessings == 1.6 23:18:00 INFO - blobuploader == 1.2.4 23:18:00 INFO - docopt == 0.6.1 23:18:00 INFO - functools32 == 3.2.3-2 23:18:00 INFO - jsonschema == 2.5.1 23:18:00 INFO - manifestparser == 1.1 23:18:00 INFO - mozInstall == 1.12 23:18:00 INFO - mozcrash == 0.17 23:18:00 INFO - mozdebug == 0.1 23:18:00 INFO - mozdevice == 0.48 23:18:00 INFO - mozfile == 1.2 23:18:00 INFO - mozhttpd == 0.7 23:18:00 INFO - mozinfo == 0.9 23:18:00 INFO - mozleak == 0.1 23:18:00 INFO - mozlog == 3.2 23:18:00 INFO - moznetwork == 0.27 23:18:00 INFO - mozprocess == 0.22 23:18:00 INFO - mozprofile == 0.28 23:18:00 INFO - mozrunner == 6.11 23:18:00 INFO - mozscreenshot == 0.1 23:18:00 INFO - mozsystemmonitor == 0.3 23:18:00 INFO - moztest == 0.7 23:18:00 INFO - mozversion == 1.4 23:18:00 INFO - psutil == 3.1.1 23:18:00 INFO - requests == 1.2.3 23:18:00 INFO - wsgiref == 0.1.2 23:18:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:00 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')]} 23:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:00 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')]} 23:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:00 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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:18:00 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 23:18:00 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 23:18:00 INFO - Using env: {'DISPLAY': ':0', 23:18:00 INFO - 'HOME': '/home/cltbld', 23:18:00 INFO - 'LANG': 'en_US.UTF-8', 23:18:00 INFO - 'LANGUAGE': 'en_US:en', 23:18:00 INFO - 'LOGNAME': 'cltbld', 23:18:00 INFO - 'MAIL': '/var/mail/cltbld', 23:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:00 INFO - 'MOZ_NO_REMOTE': '1', 23:18:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:00 INFO - 'NO_EM_RESTART': '1', 23:18:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:00 INFO - 'PWD': '/builds/slave/test', 23:18:00 INFO - 'SHELL': '/bin/bash', 23:18:00 INFO - 'SHLVL': '1', 23:18:00 INFO - 'TERM': 'linux', 23:18:00 INFO - 'TMOUT': '86400', 23:18:00 INFO - 'USER': 'cltbld', 23:18:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:00 INFO - '_': '/tools/buildbot/bin/python'} 23:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 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)) 23:18:00 INFO - Cleaning up... 23:18:00 INFO - Return code: 0 23:18:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:18:00 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')]} 23:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:00 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')]} 23:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:00 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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:18:00 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 23:18:00 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 23:18:00 INFO - Using env: {'DISPLAY': ':0', 23:18:00 INFO - 'HOME': '/home/cltbld', 23:18:00 INFO - 'LANG': 'en_US.UTF-8', 23:18:00 INFO - 'LANGUAGE': 'en_US:en', 23:18:00 INFO - 'LOGNAME': 'cltbld', 23:18:00 INFO - 'MAIL': '/var/mail/cltbld', 23:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:00 INFO - 'MOZ_NO_REMOTE': '1', 23:18:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:00 INFO - 'NO_EM_RESTART': '1', 23:18:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:00 INFO - 'PWD': '/builds/slave/test', 23:18:00 INFO - 'SHELL': '/bin/bash', 23:18:00 INFO - 'SHLVL': '1', 23:18:00 INFO - 'TERM': 'linux', 23:18:00 INFO - 'TMOUT': '86400', 23:18:00 INFO - 'USER': 'cltbld', 23:18:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:00 INFO - '_': '/tools/buildbot/bin/python'} 23:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:18:00 INFO - Cleaning up... 23:18:00 INFO - Return code: 0 23:18:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:18:00 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')]} 23:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:00 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')]} 23:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:00 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 0x7f00358aaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118a0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, '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': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', '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 23:18:00 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 23:18:00 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 23:18:00 INFO - Using env: {'DISPLAY': ':0', 23:18:00 INFO - 'HOME': '/home/cltbld', 23:18:00 INFO - 'LANG': 'en_US.UTF-8', 23:18:00 INFO - 'LANGUAGE': 'en_US:en', 23:18:00 INFO - 'LOGNAME': 'cltbld', 23:18:00 INFO - 'MAIL': '/var/mail/cltbld', 23:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:00 INFO - 'MOZ_NO_REMOTE': '1', 23:18:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:00 INFO - 'NO_EM_RESTART': '1', 23:18:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:00 INFO - 'PWD': '/builds/slave/test', 23:18:00 INFO - 'SHELL': '/bin/bash', 23:18:00 INFO - 'SHLVL': '1', 23:18:00 INFO - 'TERM': 'linux', 23:18:00 INFO - 'TMOUT': '86400', 23:18:00 INFO - 'USER': 'cltbld', 23:18:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:00 INFO - '_': '/tools/buildbot/bin/python'} 23:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:00 INFO - Downloading/unpacking flake8 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:18:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18: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 23:18:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:18:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 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 23:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:18:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:03 INFO - no previously-included directories found matching 'docs/_build' 23:18:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:18:03 INFO - Running setup.py install for pep8 23:18:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:03 INFO - no previously-included directories found matching 'docs/_build' 23:18:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:18:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:18:03 INFO - Cleaning up... 23:18:03 INFO - Return code: 0 23:18:03 INFO - Running post-action listener: _resource_record_post_action 23:18:03 INFO - Running post-action listener: _start_resource_monitoring 23:18:03 INFO - Starting resource monitoring. 23:18:03 INFO - ##### 23:18:03 INFO - ##### Finished create-virtualenv step (success) 23:18:03 INFO - ##### 23:18:03 INFO - ##### 23:18:03 INFO - ##### Running install step. 23:18:03 INFO - ##### 23:18:03 INFO - Running pre-action listener: _resource_record_pre_action 23:18:03 INFO - Running main action method: install 23:18:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:04 INFO - Reading from file tmpfile_stdout 23:18:04 INFO - Detecting whether we're running mozinstall >=1.0... 23:18:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:18:04 INFO - Reading from file tmpfile_stdout 23:18:04 INFO - Output received: 23:18:04 INFO - Usage: mozinstall [options] installer 23:18:04 INFO - Options: 23:18:04 INFO - -h, --help show this help message and exit 23:18:04 INFO - -d DEST, --destination=DEST 23:18:04 INFO - Directory to install application into. [default: 23:18:04 INFO - "/builds/slave/test"] 23:18:04 INFO - --app=APP Application being installed. [default: firefox] 23:18:04 INFO - mkdir: /builds/slave/test/build/application 23:18:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:18:20 INFO - Reading from file tmpfile_stdout 23:18:20 INFO - Output received: 23:18:20 INFO - /builds/slave/test/build/application/firefox/firefox 23:18:20 INFO - Running post-action listener: _resource_record_post_action 23:18:20 INFO - ##### 23:18:20 INFO - ##### Finished install step (success) 23:18:20 INFO - ##### 23:18:20 INFO - ##### 23:18:20 INFO - ##### Running run-tests step. 23:18:20 INFO - ##### 23:18:20 INFO - Running pre-action listener: _resource_record_pre_action 23:18:20 INFO - Running main action method: run_tests 23:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:18:20 INFO - Python 2.7.3 23:18:20 INFO - Return code: 0 23:18:20 INFO - grabbing minidump binary from tooltool 23:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:18:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121c050>, '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 0x1188170>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1188620>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:18:20 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 23:18:20 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 23:18:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:18:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:18:20 INFO - Return code: 0 23:18:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:18:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:18:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:18:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:18:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:18:21 INFO - Return code: 0 23:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 23:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 23:18:21 INFO - Using env: {'DISPLAY': ':0', 23:18:21 INFO - 'HOME': '/home/cltbld', 23:18:21 INFO - 'LANG': 'en_US.UTF-8', 23:18:21 INFO - 'LANGUAGE': 'en_US:en', 23:18:21 INFO - 'LOGNAME': 'cltbld', 23:18:21 INFO - 'MAIL': '/var/mail/cltbld', 23:18:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:18:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:21 INFO - 'MOZ_NO_REMOTE': '1', 23:18:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:21 INFO - 'NO_EM_RESTART': '1', 23:18:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:21 INFO - 'PWD': '/builds/slave/test', 23:18:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:18:21 INFO - 'SHELL': '/bin/bash', 23:18:21 INFO - 'SHLVL': '1', 23:18:21 INFO - 'TERM': 'linux', 23:18:21 INFO - 'TMOUT': '86400', 23:18:21 INFO - 'USER': 'cltbld', 23:18:21 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708', 23:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:21 INFO - '_': '/tools/buildbot/bin/python'} 23:18:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 23:18:22 INFO - mozversion application_buildid: 20160606203027 23:18:22 INFO - mozversion application_changeset: 580436387d62e0a40fcc781832c3291858121816 23:18:22 INFO - mozversion application_display_name: Nightly 23:18:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:18:22 INFO - mozversion application_name: Firefox 23:18:22 INFO - mozversion application_remotingname: firefox 23:18:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:18:22 INFO - mozversion application_vendor: Mozilla 23:18:22 INFO - mozversion application_version: 50.0a1 23:18:22 INFO - mozversion platform_buildid: 20160606203027 23:18:22 INFO - mozversion platform_changeset: 580436387d62e0a40fcc781832c3291858121816 23:18:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:18:22 INFO - mozversion platform_version: 50.0a1 23:18:22 INFO - using testdate: 1465280302 23:18:22 INFO - actual date: 1465280302 23:18:22 INFO - starting webserver on 'localhost:52639' 23:18:22 INFO - SUITE-START | Running 2 tests 23:18:22 INFO - TEST-START | dromaeo_css 23:18:22 INFO - operating with platform_type : linux_ 23:18:22 INFO - Initialising browser for dromaeo_css test... 23:18:22 INFO - TEST-INFO | started process 8407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplL1_CG/profile http://localhost:52639/getInfo.html) 23:18:25 INFO - PROCESS | 8407 | __metrics Screen width/height:1600/1200 23:18:25 INFO - PROCESS | 8407 | colorDepth:24 23:18:25 INFO - PROCESS | 8407 | Browser inner width/height: 1024/697 23:18:25 INFO - PROCESS | 8407 | __metrics 23:18:26 ERROR - PROCESS | 8407 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:18:26 INFO - PROCESS | 8407 | console.error: PushService: 23:18:26 INFO - PROCESS | 8407 | stateChangeProcessEnqueue: Error transitioning state 23:18:26 INFO - PROCESS | 8407 | UnknownError 23:18:27 INFO - TEST-INFO | 8407: exit 0 23:18:27 INFO - Browser initialized. 23:18:27 INFO - Running cycle 1/1 for dromaeo_css test... 23:18:27 INFO - TEST-INFO | started process 8490 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplL1_CG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:39 INFO - PROCESS | 8490 | RSS: Main: 156569600 23:18:39 INFO - PROCESS | 8490 | 23:19:30 INFO - PROCESS | 8490 | 1465280370120 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] 23:21:37 INFO - PROCESS | 8490 | Cycle 1(1): loaded http://localhost:52639/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52639/tests/dromaeo/cssquery-ext.html) 23:21:38 INFO - PROCESS | 8490 | RSS: Main: 263122944 23:21:38 INFO - PROCESS | 8490 | 23:24:41 INFO - PROCESS | 8490 | Cycle 1(1): loaded http://localhost:52639/tests/dromaeo/cssquery-ext.html (next: http://localhost:52639/tests/dromaeo/cssquery-jquery.html) 23:24:41 INFO - PROCESS | 8490 | RSS: Main: 246550528 23:24:41 INFO - PROCESS | 8490 | 23:27:43 INFO - PROCESS | 8490 | Cycle 1(1): loaded http://localhost:52639/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52639/tests/dromaeo/cssquery-mootools.html) 23:27:44 INFO - PROCESS | 8490 | RSS: Main: 244105216 23:27:44 INFO - PROCESS | 8490 | 23:30:46 INFO - PROCESS | 8490 | Cycle 1(1): loaded http://localhost:52639/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52639/tests/dromaeo/cssquery-prototype.html) 23:30:47 INFO - PROCESS | 8490 | RSS: Main: 235659264 23:30:47 INFO - PROCESS | 8490 | 23:33:49 INFO - PROCESS | 8490 | Cycle 1(1): loaded http://localhost:52639/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52639/tests/dromaeo/cssquery-yui.html) 23:33:50 INFO - PROCESS | 8490 | RSS: Main: 229724160 23:33:50 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | Cycle 1(1): loaded http://localhost:52639/tests/dromaeo/cssquery-yui.html (next: http://localhost:52639/tests/dromaeo/cssquery-dojo.html) 23:36:53 INFO - PROCESS | 8490 | RSS: Main: 291446784 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | __start_tp_report 23:36:53 INFO - PROCESS | 8490 | _x_x_mozilla_page_load 23:36:53 INFO - PROCESS | 8490 | _x_x_mozilla_page_load_details 23:36:53 INFO - PROCESS | 8490 | |i|pagename|runs| 23:36:53 INFO - PROCESS | 8490 | |0;dojo.html;3064.1879901826005;3087.073877836649;3091.273550715581;3101.782875198737;3160.6839316068395;5141.640085194038;5223.085959957011;5298.390685071213;5338.412774594792;5344.625876188668;5324.148136298193;5348.304720386344;5376.274202982596;5386.326709161355;5430.59270554709;4766.475687674361;4795.424549054101;4854.927176092361;4866.829660961867;4942.604591632678;4900.558949694527;4928.457869634355;4929.630277729167;4985.900281994358;4990;4731.432228132629;4779.52204779521;4797.040591881603;4812.855614331541;4819.638527110481;4787.80848766049;4859.538343857328;4870.537298956593;4876.926846097312;4900.803967841282;4693.084848454553;4841.540053694894;4848.296996935424;4851.199552073904;4893.730844803535;5360.705161216131;5363.6245462817615;5365.746386517672;5386.165144402624;5396.10964190908;5780.421957804186;5807.419258074201;5817.854553636151;5830.737616807254;5833.358330583633;5465.617406781527;5670.6030577859565;5711.971440142815;5781.392953739839;5802.535797136219;5361.410244873061;5404.32445944257;5415.079436495806;5415.350157981067;5434.239206511092;5945.286565612154;5978.222831031938;5980.461758441761;5981.581289309794;5982.1325907744;5427.782888684408;5521.641093329001;5566.638168519034;5582.218489411485;5718.656880587178;5664.68844213564;5709.8287051388525;5716.314042314865;5718.313802343745;5723.742431590588;5632.098864181711;5646.2942132233475;5648.378678345379;5651.208830763697;5652.265205523256;5506.449355064461;5624.465675760796;5653.689047102449;5672.688002159838;5713.714314284269;4337.132573485315;4345.174416860787;4345.869623911288;4346.2828633275785;4380.364847097217;3783.7729736215365;3876.9806150969066;3887.047673320055;3898.6491215790716;3937.310970580137;5316.521513063843;5351.224072509543;5366.356037275474;5386.326709161355;5388.137897936312;5762.971185144132;5794.304683438015;5796.188533605298;5804.245448091721;5845.947729408747;5213.478652134756;5294.444083371268;5388.218708287276;5394.865128371744;5395.595330350239;4801.135795556764;4802.495737947535;4823.758812059453;4824.300476430898;4866.124097662455;5953.3451320354725;5969.164316995624;5971.790987315423;5972.910406343908;6005.189299444606;5498.835034948958;5768.653880767168;5809.50619197373;5823.505002074871;5850.882982340329;5920.555958303058;5964.761409543569;5972.13404056418;5973.850653733692;5976.223090998143;5673.120666296731;5674.74463649145;5679.5172410345585;5686.516646085129;5686.77252909879;5485.945140548543;5708.058170402003;5737.168110623853;5764.567657425626;5782.306123265067;5646.040173170489;5651.519620832275;5659.462351076641;5661.377248502579;5662.716864156858;5571.8607034822835;5606.85982850432;5628.549716022627;5631.831045068655;5635.859103522281;5210.817621383233;5401.081816091352;5433.538149257357;5455.263539422127;5495.862603434946;5629.127485239795;5647.88704225905;5677.148427735872;5695.971520142372;5709.314882214161;5290.994711005051;5483.533899618417;5515.58633102527;5519.724013799214;5553.6390134641115 23:36:53 INFO - PROCESS | 8490 | |1;ext.html;587.4213899309179;611.5535658968952;621.8103478439074;635.6059243269173;640.4396153365805;31570;43056;43865.12269754607;44078.55921440785;44214.33678494825;45752.9411176497;46306.76846615762;46331.76834115825;46421.53578464215;46514.76742616291;8808.515531645757;8964.193222609963;8978.86531702025;8988.550572471382;8989.325800564951;8853.778655533599;8952.373333866599;8968.910310896905;8995.685151019716;9002.369834111614;8870.06864279251;9060.04869488704;9066.410683305598;9069.546522673872;9072.818543629124;8972.46165230085;9036.728898133066;9050.411723238001;9050.77373065672;9052.864207036866;8661.220490155853;8754.168354006359;8758.080401557809;8762.737117886474;8763.912360876373;42920.57079429197;43279;43289;43312.56687433148;43360.3495947561;3428.9142771430666;3613.602503724588;4013.9598604013877;4266.0188156724125;4308.246056940055;33166.834165829256;33338.3332333352;33350.833245833855;33362.33275334504;33457.83271083629;4151.543330233671;4310.482742070972;4447.08834688888;4569.657275704336;4571.22289210835;335924;339861;339938;341187;341311.9999999975;60648.39351606516;60917.69541152266;61061.38938610646;61112.08331875026;61161.69419152876;59123.704381477815;59376;59432.405675943555;59483.70258148768;59520;16528.09095499759;16662.50012499627;16739.665206695798;16759.329626815033;16767;12599.433025513874;12605.55880544195;12637.684057898443;12642.810357844639;12648.304343261025;17724.727801205587;18702.25190992372;18789.62420751577;18818.15318310679;18962.620747585246;6080.483158931452;6202.906956395657;6243.313235544087;6265.404786545271;6350.587211831216;1015.294370412571;1025.1849779425374;1051.4952822645173;1073.1146803886825;1074.5218377821793;32423;32539.34921301608;32656.836715816746;33311.50032749511;33384.83307583447;1121.3103941076179;1129.2039112425884;1148.4315263944402;1160.5937921727327;1208.6011616166643;16844.494665160066;17146.142692865556;17147.828521714873;17254.827451725323;17263.3957811476;519588;522933;523663;523762;524711;59313.703431482565;60441;60444.69777651084;60468.39531604628;60498;1265.943032563537;1355.7152997870558;1386.8821150202143;1401.53048728675;1430.6852492451644;30141.849290753406;34458.655413445544;34870.30259394849;34906.65093349034;35145.64854351424;52342.738286308326;53425.73287133539;53552.19671705006;53565.732171338896;53669.73165134149;508908;528214;529341;530470;530849;37858.43212351872;42262.57737422586;43696.78151609222;43906;44537;48931.26603101027;49840.003199936524;50133.619447838915;50900.745496272284;50939;40134.59865401308;42172;42464.15071698487;42642.57357426386;43502.34746478868;41635;41862;42200.78899605482;42770.5722942779;43080;42111.578884211995;42782.35826462668;43001.56998429976;43315.78342108269;43382.34926476042;35534;37008.62991370052;37830.08722666989;38365.424518632804;38500.42249366206;28068.71931280661;28905.855470722512;29028;29036.419271614028;29164.7083529162 23:36:53 INFO - PROCESS | 8490 | |2;jquery.html;381.91025109099365;389.9454076429302;397.9025138840981;399.53055160186784;414.5543540693755;13920.860791392082;16893.1553422329;19082.713759293605;19742.70385944212;19787.604247915053;14185.219812910285;17827.46517604471;18670.626587468276;18750.718739218923;19119.330823421184;5542.0855558832845;5696.287964004499;5709.172170035363;5713.74288157034;5738.483536481715;4109;5602.1596760486045;5657.632253903504;5933.376995415484;6240.157578726859;6259.342769009257;6280.780172693957;6286.245650521943;6305.117283580303;6314.052892066204;6030.065339872327;6152.907706384408;6156.138140660333;6159.538034647419;6209.192804935375;5269.86825329366;5363.222332761728;5382.0312343778105;5385.892282154335;5386.73066346685;22425.215117470972;23096;23176.65235021476;23336.766632333798;23428.414289642726;2261.3555136786104;2274.283600665785;2428.3385917430537;2438.244144315268;2456.508698260337;13864.930675346659;14302.213378264296;14411.486793886595;14460.132392056405;14754.26228688572;1918.213532451688;1963.1460314763124;1985.4242269741758;2001.5296405344732;2004.5990801839691;78254.21745782526;79443;81123.9999999994;81741.77387339254;83193;10250.38497690141;10406.28296331112;10525.421101839473;10663.680089597325;10793.514291856885;14916.552503424915;14981.101133932;15002.399904003718;15013.174275414956;15029.549113526393;7747.573883436465;7766.184550622103;7898.881516777253;8048.637811298506;8061.556614386148;6865.965670171618;7496.212897645778;7623.733169339046;7698.730544430918;7754.612269386621;6028.638281703112;6396.5522413431545;6422.42198202164;6594.560012795297;6727.150033740761;1877.8591605629358;1891.423115949621;1922.4617107210004;1942.9222831086881;1973.7335459713042;965.3435663748578;1017.8422344536609;1076.822324316479;1087.6736978906297;1164.5109054197087;18937.715934260996;19416.90291548533;20053.799462005485;20056;20079.899600502195;1005.7988402319565;1046.539522610049;1061.2253055269696;1062.7077553672648;1076.0853274716585;10749.677509674722;11323.263987840764;11371.31772093677;11374.601888933848;11400.771984560428;474329;483304;488505;489524;490948.63577046373;79159;79781.20218797853;80111;80747;80762.59618701987;1011.7066050845173;1041.7239431550797;1042.358949246208;1054.5781687325132;1063.048571528493;17219.13680173317;17253.827461725723;17386.391476298268;17426;17578.296868125133;37610.803081737264;39683.60316396914;39740;44151;44906.77546612246;55010;55641.1576784448;60236.69881650564;60906;61028.38971610227;5363.544098751494;5418.512333889968;7927.088384835587;8035.919640803751;8105.311048560702;12200.328981906081;13500.32498375097;13512.189268643913;13583;13762.174269543459;11617.419129043345;11665.59170429031;11937.641870743892;11941.164118511968;12067.75864482688;11455.828162577402;11471.254368466356;11471.885281147082;11730.237534560227;11739.530418783153;11638.767224655629;12203.511859525519;12233.877661223274;12290.877091228973;12325.260484370967;8030.196980301923;8150.796230094295;8244.051934027664;8259.132791056853;8356.958215208884;11700.94149529281;11862.762744745229;11937.343446110548;11984.46069926838;12131.636050918487 23:36:53 INFO - PROCESS | 8490 | |3;mootools.html;1320.564213809443;1476.9409223631055;1485.331600779649;1668.1992643531105;1824.82664146906;8210.384221183413;8533.615987280571;8575.271101956334;8819.029906710257;8825.646974121044;8549.102344253857;8667.783305417352;8755;8756.518391488466;8759.562021898895;1992.4321568353023;2266.2974477911816;2331.347222777625;2354.482013956927;2374.857508549484;2366.7869891709743;2375.10933399975;2376.4296568823443;2377.0610608809598;2382.142428725666;2323.7908588227056;2337.4857531343164;2366.763323667628;2373.46597015671;2376.9762302376926;1967.8622496425255;2189.813865821407;2246.067881829039;2254.2448279826112;2259.6271615183473;2289.759575244592;2305.216226482987;2311.39903625057;2313.641385585237;2314.1437668062927;8684.956575217084;8742.213200811893;8743.12568743127;8747.16901894319;8755.25580325673;4928.950710492921;5005.89988200234;5021;5065.088284108859;5070.138076527;3152.8423578820966;3234.450143475591;3336.466165413522;3357.7985320880825;3364.899053028413;1091.1052936591998;1121.5681962444437;1135.73310272086;1143.982840257397;1148.9253198542067;11850.525978960832;11863.881361186364;11882.881171188264;11891.940540297242;11893.108016898681;4345.391645169679;4437.534058923831;4519.231730605805;4545.068261006486;4551.340055691906;10272.486375681186;10277.434741089312;10303.536340864679;10305.690829275134;10309.381437113798;5068.417132029864;5087.26234695967;5088.109580823341;5094.465081166424;5097.617678674114;4009.959900401017;4512.120136573402;4654.86035418938;4666.580007799246;4735.266033764772;3904.2191561687778;3977.960220397817;4029.6373326400767;4048.5344185418467;4071.450354202204;1261.2999785119348;1275.508929062299;1279.9808002879963;1295.352323838081;1300.7073408483016;1005.2058873489862;1006.3157053203745;1019.90820826126;1021.9233557483218;1024.4928760263717;5756.136579513023;6082.239720034996;6137.693115344215;6216.471599913967;6225.3774622537385;473.90521895621015;484.0730002046113;494.3672099712374;498.48655884439086;498.51145877039977;4064.634182923492;4064.8170832312617;4104.138130992525;4123.381492776048;4150.169966006811;10310.123639490726;10609.363438193734;10623.20325975555;10675.199360048027;10700.571977120984;10561.524731387106;10583.417912014913;10595.470226488645;10603.734906627396;10656.57373705043;7739.071311442551;8022.75931722061;8038.799030024296;8104.298097399612;8105;5961.976433310263;6002.669853157943;6095.542834287357;6134;6196.876062478816;10071.446070466194;10252.58989640406;10266.435346056038;10276.22928280367;10278.537465813906;9926.702198934043;10209.642662506776;10374.118199953089;10379.169666426818;10412.635557755442;6906.102206713095;7527.2849079337375;7583.810404739926;7710.614469276402;7772.689092436465;8588.184122508352;8603.05366409707;8625.051244362989;8659.913400865911;8667.869981950402;7482.915547968015;7774.144844067261;7811.281518066016;7827.804304892423;7904.328132108835;7481.738139165103;7538.623068846646;7538.698452061856;7641.465097443348;7766.417518686008;7026.508144429841;7131.393831524379;7144.1784194818265;7150.249223831423;7160.856782864209;5128.692278463304;5206.817761378334;5298.072837253389;5321.54766844807;5377.327834020747;5160.432352441153;5377.973110134424;5487.259220005408;5521.69630670317;5610.410906854885 23:36:53 INFO - PROCESS | 8490 | |4;prototype.html;318.51426574473925;329.71479670085375;334.02631329674;336.41631768881;339.9167204035011;5224.68651880887;5264.420913699495;5290.709011004393;5293.179557168635;5315.548178404831;4986.900261994758;5070.923936140952;5103.413107492639;5150.871228219296;5287.947120528794;3946.703997200207;4002.899927501807;4073.7555746655294;4120.896977575555;4149.792510374485;4059.9594004060023;4075.347944328908;4078.632923036926;4117.9176416471655;4141.917161656765;3855.306044911915;4058.715889887709;4083.183363327332;4092.6930480213955;4096.4265002899465;4024.215278020787;4035.636792688643;4036.667899593127;4054;4078.062045729469;3569.375359312136;3596.1549035976523;3605.5853576838736;3619.059044648384;3622.9275414491826;4832.251001094836;5140.948590514085;5161.922571161435;5162.92255616166;5169.405518365398;4633.976830115862;4646.535346465326;4666.786635474767;4666.859994200179;4680.7191568506;5456.809011684572;5470.261514695545;5496.725163741837;5500.202470641774;5502.8349149525175;3857.691384689246;3942.48747662802;3945.6646185074014;3948.5261768587666;3969.940450893239;5651.660900345952;5655.802046928337;5694.629849059798;5702.885942281131;5719.28508936383;3985.561588225293;3991.1020020495157;4003.3594624859884;4005.1989602079116;4012.498437695288;4057.837686492507;4059.4316795648633;4065.2682517146604;4080.3471444568727;4088.161926805057;4567.337736028324;4621.468531118964;4628.259478483426;4628.352030715702;4649.837255696035;5475.589330800206;5490.227435145226;5492.176173573996;5496.615236933376;5517.565432987452;1315.224017829485;1363.2093385836192;1372.4921778941655;1405.5150972914328;1410.978835317471;881.6870011146079;893.5264309915755;897.6050537763364;900.7793090692691;911.694582314932;4656.301554766745;4707.328898241143;4728.092223282725;4728.692634978715;4735.408073990751;4692.53074692528;4873;4935.679180853233;5022.196448568212;5024.095662780662;4666.8133274668635;4687.859364219079;4704.223803072455;4719.221328480831;4778.713277203379;2649.2582077018465;2710.3630646797988;2727.277271523048;2732.617433559303;2732.8496932668495;5054.267131265946;5222.294990176353;5239.423663397023;5241.947580524146;5270.130428479335;4906.386701662293;5116.846494605168;5129.204973229155;5139.715071232192;5147.176451767774;5155.871103222449;5170.767315470813;5172.508611681884;5221.947780522222;5224.093205979866;4822.9758851205515;4944.950550494449;4968.975155124202;4987.825426110068;5068.315777370155;5012.523810238022;5023.773930173078;5032.0690672225755;5039.740064983754;5051.419086805065;4864.95135048645;5003.098822447447;5088.592912566912;5196.740162991911;5207.2449494823795;3518.437050071976;3572.7670206121174;3601.657842504958;3638.2723455309574;3652.4886515887265;5001.699898006132;5028.949710502849;5068.428787076018;5127.282180494808;5129.8717532062;4984.352034235526;4988.27669987857;5007.519773757349;5022.81051532287;5034.949650503448;4638.6752927294765;4927.704337739747;4982.6512144149565;5276.709780962084;5328.280682108021;5340.839774806762;5343.599230057839;5363.115086010765;5372.785088596569;5392.865178370572;5481.698506582176;5491.615586909038;5511.4213007633625;5555.694436806015;5556.91664625039;5199.778052157815;5348.277982472472;5445.918311225256;5464.672119672832;5480.424555421783 23:36:53 INFO - PROCESS | 8490 | |5;yui.html;744.836136050069;773.1263672050582;781.19564677235;811.1118325433652;837.225566351125;14955.775663365059;15516;15765.921170394146;16140.83859161408;16896.324147034105;16436.342546298165;16474.588135296624;16540.33838646456;16567.171641417943;16585;680.581442412915;817.3951276055706;829.3448175940999;831.7712629027005;831.7795784117213;791.8139237279264;810.9026916769966;821.8972628421448;822.7901885019312;823.7075838077486;852.2500199824146;853.871919212117;859.3855393393723;859.5057841740993;859.7721603774974;713.5718568858695;823.8475881961797;855.2174760094506;855.6876739989921;856.0754385263931;619.9659018753966;620.3579295429209;620.4168082002902;620.6679426506797;620.9534284928649;15762;15811.446599368966;15811.841881581153;15815.446459373867;15824.841751582451;13992.370343334473;14295.499657511937;14321.856781432156;14324.57026289213;14345.139291642536;13423.865761342358;13494.257815820127;13555;13694.452221911211;13709.17744935297;4428.822847086113;4429.867103986886;4433.645308375322;4462.977685111586;4637.281221410687;79240;79681.80477292788;80255;80852;80965.19034809695;10605.787884242272;10874.075703565128;10904.964028417287;10961.12311015117;11188.832167517494;21395.89302053511;21539.2461263855;21550.676739848914;21662.89168554147;21960.89019554924;8308.5430301334;8342.582870856433;8374.204450577185;8426.53654049021;8441.324694024463;4547.113312903952;4599.632029437704;4670.346151538787;4685.531446855287;4702.952970470252;3432.6567343265474;3552.1297282166033;3559.715222782171;3563.893083207508;3564.500969864221;594.8126340202822;596.0760820727941;604.4076804731388;617.8949578571653;622.5362105231632;566.8214512428572;667.7195577857311;668.9933100668931;669.6986356139756;703.1878180701298;9414.340996130342;10105.646302379382;10133.08802207805;10136.797264054678;10138.645147419953;671.2414971082743;680.7549282258383;682.8429461223947;683.0573808144828;693.6046453521545;4488.887777805581;4705.835295764632;4796.103228511131;4823;4910.361652985161;91250;110511.44744276228;120195;121177;125765.37117314355;84603;92494;93636;95364;95937;53334.46665533295;53802.157805797666;60728;77972.83040754394;87495.56252218699;9975.746491193113;10290.485475726333;10337.224708146769;10406.791864162826;10408.947955260175;50257.74871125621;50492;51751;51829.48170518397;53629;65816.6709166451;66774;70647.64676176586;73135.63432183018;73614.26385736288;13004;13051.477940882638;13196.538121165713;13554.796678050034;13666.658333541742;14744.705105898036;15192.772108418603;15314.770278445609;15325.233738312816;15868.365265389717;12275.93862030684;12345.876541234473;12360.01119910423;13027.348632568523;14670.486532971296;13733.519326823513;14017.299135043411;14242.28788560547;14295.64260893486;14333.713325733635;13833.170009799444;14323.212223327817;14464.276786160439;14492.710145797237;14534.636634084232;11412.372319522507;11473.655790326304;11711.941440292743;11831;11874.16880818369;11115;11163.832542511705;11755.471003804678;12181.69545761363;12240.816387754012 23:36:53 INFO - PROCESS | 8490 | __end_tp_report 23:36:53 INFO - PROCESS | 8490 | __start_cc_report 23:36:53 INFO - PROCESS | 8490 | _x_x_mozilla_cycle_collect,924 23:36:53 INFO - PROCESS | 8490 | __end_cc_report 23:36:53 INFO - PROCESS | 8490 | __startTimestamp1465281413264__endTimestamp 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | ------- Summary: start ------- 23:36:53 INFO - PROCESS | 8490 | Number of tests: 6 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | [#0] dojo.html Cycles:165 Average:5310.34 Median:5483.53 stddev:617.84 (11.3%) stddev-sans-first:594.08 23:36:53 INFO - PROCESS | 8490 | Values: 3064.2 3087.1 3091.3 3101.8 3160.7 5141.6 5223.1 5298.4 5338.4 5344.6 5324.1 5348.3 5376.3 5386.3 5430.6 4766.5 4795.4 4854.9 4866.8 4942.6 4900.6 4928.5 4929.6 4985.9 4990.0 4731.4 4779.5 4797.0 4812.9 4819.6 4787.8 4859.5 4870.5 4876.9 4900.8 4693.1 4841.5 4848.3 4851.2 4893.7 5360.7 5363.6 5365.7 5386.2 5396.1 5780.4 5807.4 5817.9 5830.7 5833.4 5465.6 5670.6 5712.0 5781.4 5802.5 5361.4 5404.3 5415.1 5415.4 5434.2 5945.3 5978.2 5980.5 5981.6 5982.1 5427.8 5521.6 5566.6 5582.2 5718.7 5664.7 5709.8 5716.3 5718.3 5723.7 5632.1 5646.3 5648.4 5651.2 5652.3 5506.4 5624.5 5653.7 5672.7 5713.7 4337.1 4345.2 4345.9 4346.3 4380.4 3783.8 3877.0 3887.0 3898.6 3937.3 5316.5 5351.2 5366.4 5386.3 5388.1 5763.0 5794.3 5796.2 5804.2 5845.9 5213.5 5294.4 5388.2 5394.9 5395.6 4801.1 4802.5 4823.8 4824.3 4866.1 5953.3 5969.2 5971.8 5972.9 6005.2 5498.8 5768.7 5809.5 5823.5 5850.9 5920.6 5964.8 5972.1 5973.9 5976.2 5673.1 5674.7 5679.5 5686.5 5686.8 5485.9 5708.1 5737.2 5764.6 5782.3 5646.0 5651.5 5659.5 5661.4 5662.7 5571.9 5606.9 5628.5 5631.8 5635.9 5210.8 5401.1 5433.5 5455.3 5495.9 5629.1 5647.9 5677.1 5696.0 5709.3 5291.0 5483.5 5515.6 5519.7 5553.6 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | [#1] ext.html Cycles:180 Average:62994.62 Median:43300.78 stddev:124910.63 (288.5%) stddev-sans-first:125173.15 23:36:53 INFO - PROCESS | 8490 | Values: 587.4 611.6 621.8 635.6 640.4 31570.0 43056.0 43865.1 44078.6 44214.3 45752.9 46306.8 46331.8 46421.5 46514.8 8808.5 8964.2 8978.9 8988.6 8989.3 8853.8 8952.4 8968.9 8995.7 9002.4 8870.1 9060.0 9066.4 9069.5 9072.8 8972.5 9036.7 9050.4 9050.8 9052.9 8661.2 8754.2 8758.1 8762.7 8763.9 42920.6 43279.0 43289.0 43312.6 43360.3 3428.9 3613.6 4014.0 4266.0 4308.2 33166.8 33338.3 33350.8 33362.3 33457.8 4151.5 4310.5 4447.1 4569.7 4571.2 335924.0 339861.0 339938.0 341187.0 341312.0 60648.4 60917.7 61061.4 61112.1 61161.7 59123.7 59376.0 59432.4 59483.7 59520.0 16528.1 16662.5 16739.7 16759.3 16767.0 12599.4 12605.6 12637.7 12642.8 12648.3 17724.7 18702.3 18789.6 18818.2 18962.6 6080.5 6202.9 6243.3 6265.4 6350.6 1015.3 1025.2 1051.5 1073.1 1074.5 32423.0 32539.3 32656.8 33311.5 33384.8 1121.3 1129.2 1148.4 1160.6 1208.6 16844.5 17146.1 17147.8 17254.8 17263.4 519588.0 522933.0 523663.0 523762.0 524711.0 59313.7 60441.0 60444.7 60468.4 60498.0 1265.9 1355.7 1386.9 1401.5 1430.7 30141.8 34458.7 34870.3 34906.7 35145.6 52342.7 53425.7 53552.2 53565.7 53669.7 508908.0 528214.0 529341.0 530470.0 530849.0 37858.4 42262.6 43696.8 43906.0 44537.0 48931.3 49840.0 50133.6 50900.7 50939.0 40134.6 42172.0 42464.2 42642.6 43502.3 41635.0 41862.0 42200.8 42770.6 43080.0 42111.6 42782.4 43001.6 43315.8 43382.3 35534.0 37008.6 37830.1 38365.4 38500.4 28068.7 28905.9 29028.0 29036.4 29164.7 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | [#2] jquery.html Cycles:180 Average:28595.90 Median:2267.82 stddev:79797.45 (3518.7%) stddev-sans-first:79993.19 23:36:53 INFO - PROCESS | 8490 | Values: 381.9 389.9 397.9 399.5 414.6 13920.9 16893.2 19082.7 19742.7 19787.6 14185.2 17827.5 18670.6 18750.7 19119.3 5542.1 5696.3 5709.2 5713.7 5738.5 4109.0 5602.2 5657.6 5933.4 6240.2 6259.3 6280.8 6286.2 6305.1 6314.1 6030.1 6152.9 6156.1 6159.5 6209.2 5269.9 5363.2 5382.0 5385.9 5386.7 22425.2 23096.0 23176.7 23336.8 23428.4 2261.4 2274.3 2428.3 2438.2 2456.5 13864.9 14302.2 14411.5 14460.1 14754.3 1918.2 1963.1 1985.4 2001.5 2004.6 78254.2 79443.0 81124.0 81741.8 83193.0 10250.4 10406.3 10525.4 10663.7 10793.5 14916.6 14981.1 15002.4 15013.2 15029.5 7747.6 7766.2 7898.9 8048.6 8061.6 6866.0 7496.2 7623.7 7698.7 7754.6 6028.6 6396.6 6422.4 6594.6 6727.2 1877.9 1891.4 1922.5 1942.9 1973.7 965.3 1017.8 1076.8 1087.7 1164.5 18937.7 19416.9 20053.8 20056.0 20079.9 1005.8 1046.5 1061.2 1062.7 1076.1 10749.7 11323.3 11371.3 11374.6 11400.8 474329.0 483304.0 488505.0 489524.0 490948.6 79159.0 79781.2 80111.0 80747.0 80762.6 1011.7 1041.7 1042.4 1054.6 1063.0 17219.1 17253.8 17386.4 17426.0 17578.3 37610.8 39683.6 39740.0 44151.0 44906.8 55010.0 55641.2 60236.7 60906.0 61028.4 5363.5 5418.5 7927.1 8035.9 8105.3 12200.3 13500.3 13512.2 13583.0 13762.2 11617.4 11665.6 11937.6 11941.2 12067.8 11455.8 11471.3 11471.9 11730.2 11739.5 11638.8 12203.5 12233.9 12290.9 12325.3 8030.2 8150.8 8244.1 8259.1 8357.0 11700.9 11862.8 11937.3 11984.5 12131.6 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | [#3] mootools.html Cycles:180 Average:5744.17 Median:4391.46 stddev:3268.69 (74.4%) stddev-sans-first:3260.95 23:36:53 INFO - PROCESS | 8490 | Values: 1320.6 1476.9 1485.3 1668.2 1824.8 8210.4 8533.6 8575.3 8819.0 8825.6 8549.1 8667.8 8755.0 8756.5 8759.6 1992.4 2266.3 2331.3 2354.5 2374.9 2366.8 2375.1 2376.4 2377.1 2382.1 2323.8 2337.5 2366.8 2373.5 2377.0 1967.9 2189.8 2246.1 2254.2 2259.6 2289.8 2305.2 2311.4 2313.6 2314.1 8685.0 8742.2 8743.1 8747.2 8755.3 4929.0 5005.9 5021.0 5065.1 5070.1 3152.8 3234.5 3336.5 3357.8 3364.9 1091.1 1121.6 1135.7 1144.0 1148.9 11850.5 11863.9 11882.9 11891.9 11893.1 4345.4 4437.5 4519.2 4545.1 4551.3 10272.5 10277.4 10303.5 10305.7 10309.4 5068.4 5087.3 5088.1 5094.5 5097.6 4010.0 4512.1 4654.9 4666.6 4735.3 3904.2 3978.0 4029.6 4048.5 4071.5 1261.3 1275.5 1280.0 1295.4 1300.7 1005.2 1006.3 1019.9 1021.9 1024.5 5756.1 6082.2 6137.7 6216.5 6225.4 473.9 484.1 494.4 498.5 498.5 4064.6 4064.8 4104.1 4123.4 4150.2 10310.1 10609.4 10623.2 10675.2 10700.6 10561.5 10583.4 10595.5 10603.7 10656.6 7739.1 8022.8 8038.8 8104.3 8105.0 5962.0 6002.7 6095.5 6134.0 6196.9 10071.4 10252.6 10266.4 10276.2 10278.5 9926.7 10209.6 10374.1 10379.2 10412.6 6906.1 7527.3 7583.8 7710.6 7772.7 8588.2 8603.1 8625.1 8659.9 8667.9 7482.9 7774.1 7811.3 7827.8 7904.3 7481.7 7538.6 7538.7 7641.5 7766.4 7026.5 7131.4 7144.2 7150.2 7160.9 5128.7 5206.8 5298.1 5321.5 5377.3 5160.4 5378.0 5487.3 5521.7 5610.4 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | [#4] prototype.html Cycles:180 Average:4401.16 Median:4975.81 stddev:1260.74 (25.3%) stddev-sans-first:1226.47 23:36:53 INFO - PROCESS | 8490 | Values: 318.5 329.7 334.0 336.4 339.9 5224.7 5264.4 5290.7 5293.2 5315.5 4986.9 5070.9 5103.4 5150.9 5287.9 3946.7 4002.9 4073.8 4120.9 4149.8 4060.0 4075.3 4078.6 4117.9 4141.9 3855.3 4058.7 4083.2 4092.7 4096.4 4024.2 4035.6 4036.7 4054.0 4078.1 3569.4 3596.2 3605.6 3619.1 3622.9 4832.3 5140.9 5161.9 5162.9 5169.4 4634.0 4646.5 4666.8 4666.9 4680.7 5456.8 5470.3 5496.7 5500.2 5502.8 3857.7 3942.5 3945.7 3948.5 3969.9 5651.7 5655.8 5694.6 5702.9 5719.3 3985.6 3991.1 4003.4 4005.2 4012.5 4057.8 4059.4 4065.3 4080.3 4088.2 4567.3 4621.5 4628.3 4628.4 4649.8 5475.6 5490.2 5492.2 5496.6 5517.6 1315.2 1363.2 1372.5 1405.5 1411.0 881.7 893.5 897.6 900.8 911.7 4656.3 4707.3 4728.1 4728.7 4735.4 4692.5 4873.0 4935.7 5022.2 5024.1 4666.8 4687.9 4704.2 4719.2 4778.7 2649.3 2710.4 2727.3 2732.6 2732.8 5054.3 5222.3 5239.4 5241.9 5270.1 4906.4 5116.8 5129.2 5139.7 5147.2 5155.9 5170.8 5172.5 5221.9 5224.1 4823.0 4945.0 4969.0 4987.8 5068.3 5012.5 5023.8 5032.1 5039.7 5051.4 4865.0 5003.1 5088.6 5196.7 5207.2 3518.4 3572.8 3601.7 3638.3 3652.5 5001.7 5028.9 5068.4 5127.3 5129.9 4984.4 4988.3 5007.5 5022.8 5034.9 4638.7 4927.7 4982.7 5276.7 5328.3 5340.8 5343.6 5363.1 5372.8 5392.9 5481.7 5491.6 5511.4 5555.7 5556.9 5199.8 5348.3 5445.9 5464.7 5480.4 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - PROCESS | 8490 | [#5] yui.html Cycles:180 Average:20255.57 Median:4475.93 stddev:28404.21 (634.6%) stddev-sans-first:28446.11 23:36:53 INFO - PROCESS | 8490 | Values: 744.8 773.1 781.2 811.1 837.2 14955.8 15516.0 15765.9 16140.8 16896.3 16436.3 16474.6 16540.3 16567.2 16585.0 680.6 817.4 829.3 831.8 831.8 791.8 810.9 821.9 822.8 823.7 852.3 853.9 859.4 859.5 859.8 713.6 823.8 855.2 855.7 856.1 620.0 620.4 620.4 620.7 621.0 15762.0 15811.4 15811.8 15815.4 15824.8 13992.4 14295.5 14321.9 14324.6 14345.1 13423.9 13494.3 13555.0 13694.5 13709.2 4428.8 4429.9 4433.6 4463.0 4637.3 79240.0 79681.8 80255.0 80852.0 80965.2 10605.8 10874.1 10905.0 10961.1 11188.8 21395.9 21539.2 21550.7 21662.9 21960.9 8308.5 8342.6 8374.2 8426.5 8441.3 4547.1 4599.6 4670.3 4685.5 4703.0 3432.7 3552.1 3559.7 3563.9 3564.5 594.8 596.1 604.4 617.9 622.5 566.8 667.7 669.0 669.7 703.2 9414.3 10105.6 10133.1 10136.8 10138.6 671.2 680.8 682.8 683.1 693.6 4488.9 4705.8 4796.1 4823.0 4910.4 91250.0 110511.4 120195.0 121177.0 125765.4 84603.0 92494.0 93636.0 95364.0 95937.0 53334.5 53802.2 60728.0 77972.8 87495.6 9975.7 10290.5 10337.2 10406.8 10408.9 50257.7 50492.0 51751.0 51829.5 53629.0 65816.7 66774.0 70647.6 73135.6 73614.3 13004.0 13051.5 13196.5 13554.8 13666.7 14744.7 15192.8 15314.8 15325.2 15868.4 12275.9 12345.9 12360.0 13027.3 14670.5 13733.5 14017.3 14242.3 14295.6 14333.7 13833.2 14323.2 14464.3 14492.7 14534.6 11412.4 11473.7 11711.9 11831.0 11874.2 11115.0 11163.8 11755.5 12181.7 12240.8 23:36:53 INFO - PROCESS | 8490 | -------- Summary: end -------- 23:36:53 INFO - PROCESS | 8490 | 23:36:53 INFO - TEST-INFO | 8490: exit 0 23:36:53 INFO - TEST-OK | dromaeo_css | took 1111885ms 23:36:53 INFO - TEST-START | kraken 23:36:53 INFO - operating with platform_type : linux_ 23:36:53 INFO - Initialising browser for kraken test... 23:36:54 INFO - TEST-INFO | started process 8673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEHfP1w/profile http://localhost:52639/getInfo.html) 23:36:57 INFO - PROCESS | 8673 | __metrics Screen width/height:1600/1200 23:36:57 INFO - PROCESS | 8673 | colorDepth:24 23:36:57 INFO - PROCESS | 8673 | Browser inner width/height: 1024/697 23:36:57 INFO - PROCESS | 8673 | __metrics 23:36:58 ERROR - PROCESS | 8673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:36:58 INFO - PROCESS | 8673 | console.error: PushService: 23:36:58 INFO - PROCESS | 8673 | stateChangeProcessEnqueue: Error transitioning state 23:36:58 INFO - PROCESS | 8673 | UnknownError 23:36:59 INFO - TEST-INFO | 8673: exit 0 23:36:59 INFO - Browser initialized. 23:36:59 INFO - Running cycle 1/1 for kraken test... 23:36:59 INFO - TEST-INFO | started process 8759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEHfP1w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:11 INFO - PROCESS | 8759 | RSS: Main: 156618752 23:37:11 INFO - PROCESS | 8759 | 23:37:13 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52639/tests/kraken/driver.html?testName=audio-beat-detection) 23:37:14 INFO - PROCESS | 8759 | RSS: Main: 214147072 23:37:14 INFO - PROCESS | 8759 | 23:37:17 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52639/tests/kraken/driver.html?testName=audio-dft) 23:37:17 INFO - PROCESS | 8759 | RSS: Main: 200888320 23:37:17 INFO - PROCESS | 8759 | 23:37:20 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52639/tests/kraken/driver.html?testName=audio-fft) 23:37:21 INFO - PROCESS | 8759 | RSS: Main: 268328960 23:37:21 INFO - PROCESS | 8759 | 23:37:23 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52639/tests/kraken/driver.html?testName=audio-oscillator) 23:37:24 INFO - PROCESS | 8759 | RSS: Main: 179986432 23:37:24 INFO - PROCESS | 8759 | 23:37:26 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52639/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:37:26 INFO - PROCESS | 8759 | RSS: Main: 183599104 23:37:26 INFO - PROCESS | 8759 | 23:37:30 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52639/tests/kraken/driver.html?testName=imaging-darkroom) 23:37:30 INFO - PROCESS | 8759 | RSS: Main: 220143616 23:37:30 INFO - PROCESS | 8759 | 23:37:33 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52639/tests/kraken/driver.html?testName=imaging-desaturate) 23:37:34 INFO - PROCESS | 8759 | RSS: Main: 220749824 23:37:34 INFO - PROCESS | 8759 | 23:37:37 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52639/tests/kraken/driver.html?testName=json-parse-financial) 23:37:37 INFO - PROCESS | 8759 | RSS: Main: 220573696 23:37:37 INFO - PROCESS | 8759 | 23:37:39 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52639/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:37:40 INFO - PROCESS | 8759 | RSS: Main: 207065088 23:37:40 INFO - PROCESS | 8759 | 23:37:41 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:37:42 INFO - PROCESS | 8759 | RSS: Main: 210747392 23:37:42 INFO - PROCESS | 8759 | 23:37:44 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:37:45 INFO - PROCESS | 8759 | RSS: Main: 217481216 23:37:45 INFO - PROCESS | 8759 | 23:37:47 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:37:48 INFO - PROCESS | 8759 | RSS: Main: 224112640 23:37:48 INFO - PROCESS | 8759 | 23:37:51 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:37:51 INFO - PROCESS | 8759 | RSS: Main: 224411648 23:37:51 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | Cycle 1(1): loaded http://localhost:52639/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52639/tests/kraken/driver.html?testName=ai-astar) 23:37:53 INFO - PROCESS | 8759 | RSS: Main: 266719232 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | __start_tp_report 23:37:53 INFO - PROCESS | 8759 | _x_x_mozilla_page_load 23:37:53 INFO - PROCESS | 8759 | _x_x_mozilla_page_load_details 23:37:53 INFO - PROCESS | 8759 | |i|pagename|runs| 23:37:53 INFO - PROCESS | 8759 | |0;ai-astar;111;104;110;111;110;110;111;112;111;111 23:37:53 INFO - PROCESS | 8759 | |1;audio-beat-detection;158;133;127;180;126;125;157;125;157;127 23:37:53 INFO - PROCESS | 8759 | |2;audio-dft;160;162;162;161;160;161;162;163;161;162 23:37:53 INFO - PROCESS | 8759 | |3;audio-fft;74;76;84;122;75;76;74;74;102;75 23:37:53 INFO - PROCESS | 8759 | |4;audio-oscillator;85;85;124;85;84;85;113;85;85;141 23:37:53 INFO - PROCESS | 8759 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;117;116 23:37:53 INFO - PROCESS | 8759 | |6;imaging-darkroom;146;147;146;145;145;146;146;146;146;147 23:37:53 INFO - PROCESS | 8759 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 23:37:53 INFO - PROCESS | 8759 | |8;json-parse-financial;54;54;52;51;51;53;54;53;52;53 23:37:53 INFO - PROCESS | 8759 | |9;json-stringify-tinderbox;60;59;59;61;60;59;61;60;60;60 23:37:53 INFO - PROCESS | 8759 | |10;stanford-crypto-aes;80;85;82;85;81;81;86;81;111;86 23:37:53 INFO - PROCESS | 8759 | |11;stanford-crypto-ccm;125;129;127;130;126;120;129;130;135;139 23:37:53 INFO - PROCESS | 8759 | |12;stanford-crypto-pbkdf2;160;165;162;163;162;159;160;158;162;162 23:37:53 INFO - PROCESS | 8759 | |13;stanford-crypto-sha256-iterative;63;64;66;62;61;65;62;65;62;61 23:37:53 INFO - PROCESS | 8759 | __end_tp_report 23:37:53 INFO - PROCESS | 8759 | __start_cc_report 23:37:53 INFO - PROCESS | 8759 | _x_x_mozilla_cycle_collect,1629 23:37:53 INFO - PROCESS | 8759 | __end_cc_report 23:37:53 INFO - PROCESS | 8759 | __startTimestamp1465281473539__endTimestamp 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | ------- Summary: start ------- 23:37:53 INFO - PROCESS | 8759 | Number of tests: 14 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.23 (2.0%) stddev-sans-first:2.35 23:37:53 INFO - PROCESS | 8759 | Values: 111.0 104.0 110.0 111.0 110.0 110.0 111.0 112.0 111.0 111.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:145.00 stddev:19.76 (13.6%) stddev-sans-first:20.03 23:37:53 INFO - PROCESS | 8759 | Values: 158.0 133.0 127.0 180.0 126.0 125.0 157.0 125.0 157.0 127.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 23:37:53 INFO - PROCESS | 8759 | Values: 160.0 162.0 162.0 161.0 160.0 161.0 162.0 163.0 161.0 162.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:16.16 (21.6%) stddev-sans-first:16.80 23:37:53 INFO - PROCESS | 8759 | Values: 74.0 76.0 84.0 122.0 75.0 76.0 74.0 74.0 102.0 75.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:85.00 stddev:20.96 (24.7%) stddev-sans-first:21.76 23:37:53 INFO - PROCESS | 8759 | Values: 85.0 85.0 124.0 85.0 84.0 85.0 113.0 85.0 85.0 141.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 23:37:53 INFO - PROCESS | 8759 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 23:37:53 INFO - PROCESS | 8759 | Values: 146.0 147.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 147.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 23:37:53 INFO - PROCESS | 8759 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.16 (2.2%) stddev-sans-first:1.13 23:37:53 INFO - PROCESS | 8759 | Values: 54.0 54.0 52.0 51.0 51.0 53.0 54.0 53.0 52.0 53.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 23:37:53 INFO - PROCESS | 8759 | Values: 60.0 59.0 59.0 61.0 60.0 59.0 61.0 60.0 60.0 60.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:83.50 stddev:9.15 (11.0%) stddev-sans-first:9.46 23:37:53 INFO - PROCESS | 8759 | Values: 80.0 85.0 82.0 85.0 81.0 81.0 86.0 81.0 111.0 86.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:129.50 stddev:5.25 (4.1%) stddev-sans-first:5.36 23:37:53 INFO - PROCESS | 8759 | Values: 125.0 129.0 127.0 130.0 126.0 120.0 129.0 130.0 135.0 139.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:162.00 stddev:2.06 (1.3%) stddev-sans-first:2.13 23:37:53 INFO - PROCESS | 8759 | Values: 160.0 165.0 162.0 163.0 162.0 159.0 160.0 158.0 162.0 162.0 23:37:53 INFO - PROCESS | 8759 | 23:37:53 INFO - PROCESS | 8759 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.50 stddev:1.79 (2.8%) stddev-sans-first:1.90 23:37:53 INFO - PROCESS | 8759 | Values: 63.0 64.0 66.0 62.0 61.0 65.0 62.0 65.0 62.0 61.0 23:37:53 INFO - PROCESS | 8759 | -------- Summary: end -------- 23:37:53 INFO - PROCESS | 8759 | 23:37:54 INFO - TEST-INFO | 8759: exit 0 23:37:54 INFO - TEST-OK | kraken | took 60467ms 23:37:54 INFO - SUITE-END | took 1172s 23:37:54 INFO - Completed test suite (00:19:32) 23:37:54 INFO - javascript benchmark 23:37:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3064.1879901826005, 3087.073877836649, 3091.273550715581, 3101.782875198737, 3160.6839316068395, 5141.640085194038, 5223.085959957011, 5298.390685071213, 5338.412774594792, 5344.625876188668, 5324.148136298193, 5348.304720386344, 5376.274202982596, 5386.326709161355, 5430.59270554709, 4766.475687674361, 4795.424549054101, 4854.927176092361, 4866.829660961867, 4942.604591632678, 4900.558949694527, 4928.457869634355, 4929.630277729167, 4985.900281994358, 4990.0, 4731.432228132629, 4779.52204779521, 4797.040591881603, 4812.855614331541, 4819.638527110481, 4787.80848766049, 4859.538343857328, 4870.537298956593, 4876.926846097312, 4900.803967841282, 4693.084848454553, 4841.540053694894, 4848.296996935424, 4851.199552073904, 4893.730844803535, 5360.705161216131, 5363.6245462817615, 5365.746386517672, 5386.165144402624, 5396.10964190908, 5780.421957804186, 5807.419258074201, 5817.854553636151, 5830.737616807254, 5833.358330583633, 5465.617406781527, 5670.6030577859565, 5711.971440142815, 5781.392953739839, 5802.535797136219, 5361.410244873061, 5404.32445944257, 5415.079436495806, 5415.350157981067, 5434.239206511092, 5945.286565612154, 5978.222831031938, 5980.461758441761, 5981.581289309794, 5982.1325907744, 5427.782888684408, 5521.641093329001, 5566.638168519034, 5582.218489411485, 5718.656880587178, 5664.68844213564, 5709.8287051388525, 5716.314042314865, 5718.313802343745, 5723.742431590588, 5632.098864181711, 5646.2942132233475, 5648.378678345379, 5651.208830763697, 5652.265205523256, 5506.449355064461, 5624.465675760796, 5653.689047102449, 5672.688002159838, 5713.714314284269, 4337.132573485315, 4345.174416860787, 4345.869623911288, 4346.2828633275785, 4380.364847097217, 3783.7729736215365, 3876.9806150969066, 3887.047673320055, 3898.6491215790716, 3937.310970580137, 5316.521513063843, 5351.224072509543, 5366.356037275474, 5386.326709161355, 5388.137897936312, 5762.971185144132, 5794.304683438015, 5796.188533605298, 5804.245448091721, 5845.947729408747, 5213.478652134756, 5294.444083371268, 5388.218708287276, 5394.865128371744, 5395.595330350239, 4801.135795556764, 4802.495737947535, 4823.758812059453, 4824.300476430898, 4866.124097662455, 5953.3451320354725, 5969.164316995624, 5971.790987315423, 5972.910406343908, 6005.189299444606, 5498.835034948958, 5768.653880767168, 5809.50619197373, 5823.505002074871, 5850.882982340329, 5920.555958303058, 5964.761409543569, 5972.13404056418, 5973.850653733692, 5976.223090998143, 5673.120666296731, 5674.74463649145, 5679.5172410345585, 5686.516646085129, 5686.77252909879, 5485.945140548543, 5708.058170402003, 5737.168110623853, 5764.567657425626, 5782.306123265067, 5646.040173170489, 5651.519620832275, 5659.462351076641, 5661.377248502579, 5662.716864156858, 5571.8607034822835, 5606.85982850432, 5628.549716022627, 5631.831045068655, 5635.859103522281, 5210.817621383233, 5401.081816091352, 5433.538149257357, 5455.263539422127, 5495.862603434946, 5629.127485239795, 5647.88704225905, 5677.148427735872, 5695.971520142372, 5709.314882214161, 5290.994711005051, 5483.533899618417, 5515.58633102527, 5519.724013799214, 5553.6390134641115], "value": 5268.030766404558, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.4213899309179, 611.5535658968952, 621.8103478439074, 635.6059243269173, 640.4396153365805, 31570.0, 43056.0, 43865.12269754607, 44078.55921440785, 44214.33678494825, 45752.9411176497, 46306.76846615762, 46331.76834115825, 46421.53578464215, 46514.76742616291, 8808.515531645757, 8964.193222609963, 8978.86531702025, 8988.550572471382, 8989.325800564951, 8853.778655533599, 8952.373333866599, 8968.910310896905, 8995.685151019716, 9002.369834111614, 8870.06864279251, 9060.04869488704, 9066.410683305598, 9069.546522673872, 9072.818543629124, 8972.46165230085, 9036.728898133066, 9050.411723238001, 9050.77373065672, 9052.864207036866, 8661.220490155853, 8754.168354006359, 8758.080401557809, 8762.737117886474, 8763.912360876373, 42920.57079429197, 43279.0, 43289.0, 43312.56687433148, 43360.3495947561, 3428.9142771430666, 3613.602503724588, 4013.9598604013877, 4266.0188156724125, 4308.246056940055, 33166.834165829256, 33338.3332333352, 33350.833245833855, 33362.33275334504, 33457.83271083629, 4151.543330233671, 4310.482742070972, 4447.08834688888, 4569.657275704336, 4571.22289210835, 335924.0, 339861.0, 339938.0, 341187.0, 341311.9999999975, 60648.39351606516, 60917.69541152266, 61061.38938610646, 61112.08331875026, 61161.69419152876, 59123.704381477815, 59376.0, 59432.405675943555, 59483.70258148768, 59520.0, 16528.09095499759, 16662.50012499627, 16739.665206695798, 16759.329626815033, 16767.0, 12599.433025513874, 12605.55880544195, 12637.684057898443, 12642.810357844639, 12648.304343261025, 17724.727801205587, 18702.25190992372, 18789.62420751577, 18818.15318310679, 18962.620747585246, 6080.483158931452, 6202.906956395657, 6243.313235544087, 6265.404786545271, 6350.587211831216, 1015.294370412571, 1025.1849779425374, 1051.4952822645173, 1073.1146803886825, 1074.5218377821793, 32423.0, 32539.34921301608, 32656.836715816746, 33311.50032749511, 33384.83307583447, 1121.3103941076179, 1129.2039112425884, 1148.4315263944402, 1160.5937921727327, 1208.6011616166643, 16844.494665160066, 17146.142692865556, 17147.828521714873, 17254.827451725323, 17263.3957811476, 519588.0, 522933.0, 523663.0, 523762.0, 524711.0, 59313.703431482565, 60441.0, 60444.69777651084, 60468.39531604628, 60498.0, 1265.943032563537, 1355.7152997870558, 1386.8821150202143, 1401.53048728675, 1430.6852492451644, 30141.849290753406, 34458.655413445544, 34870.30259394849, 34906.65093349034, 35145.64854351424, 52342.738286308326, 53425.73287133539, 53552.19671705006, 53565.732171338896, 53669.73165134149, 508908.0, 528214.0, 529341.0, 530470.0, 530849.0, 37858.43212351872, 42262.57737422586, 43696.78151609222, 43906.0, 44537.0, 48931.26603101027, 49840.003199936524, 50133.619447838915, 50900.745496272284, 50939.0, 40134.59865401308, 42172.0, 42464.15071698487, 42642.57357426386, 43502.34746478868, 41635.0, 41862.0, 42200.78899605482, 42770.5722942779, 43080.0, 42111.578884211995, 42782.35826462668, 43001.56998429976, 43315.78342108269, 43382.34926476042, 35534.0, 37008.62991370052, 37830.08722666989, 38365.424518632804, 38500.42249366206, 28068.71931280661, 28905.855470722512, 29028.0, 29036.419271614028, 29164.7083529162], "value": 20730.18177551096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.91025109099365, 389.9454076429302, 397.9025138840981, 399.53055160186784, 414.5543540693755, 13920.860791392082, 16893.1553422329, 19082.713759293605, 19742.70385944212, 19787.604247915053, 14185.219812910285, 17827.46517604471, 18670.626587468276, 18750.718739218923, 19119.330823421184, 5542.0855558832845, 5696.287964004499, 5709.172170035363, 5713.74288157034, 5738.483536481715, 4109.0, 5602.1596760486045, 5657.632253903504, 5933.376995415484, 6240.157578726859, 6259.342769009257, 6280.780172693957, 6286.245650521943, 6305.117283580303, 6314.052892066204, 6030.065339872327, 6152.907706384408, 6156.138140660333, 6159.538034647419, 6209.192804935375, 5269.86825329366, 5363.222332761728, 5382.0312343778105, 5385.892282154335, 5386.73066346685, 22425.215117470972, 23096.0, 23176.65235021476, 23336.766632333798, 23428.414289642726, 2261.3555136786104, 2274.283600665785, 2428.3385917430537, 2438.244144315268, 2456.508698260337, 13864.930675346659, 14302.213378264296, 14411.486793886595, 14460.132392056405, 14754.26228688572, 1918.213532451688, 1963.1460314763124, 1985.4242269741758, 2001.5296405344732, 2004.5990801839691, 78254.21745782526, 79443.0, 81123.9999999994, 81741.77387339254, 83193.0, 10250.38497690141, 10406.28296331112, 10525.421101839473, 10663.680089597325, 10793.514291856885, 14916.552503424915, 14981.101133932, 15002.399904003718, 15013.174275414956, 15029.549113526393, 7747.573883436465, 7766.184550622103, 7898.881516777253, 8048.637811298506, 8061.556614386148, 6865.965670171618, 7496.212897645778, 7623.733169339046, 7698.730544430918, 7754.612269386621, 6028.638281703112, 6396.5522413431545, 6422.42198202164, 6594.560012795297, 6727.150033740761, 1877.8591605629358, 1891.423115949621, 1922.4617107210004, 1942.9222831086881, 1973.7335459713042, 965.3435663748578, 1017.8422344536609, 1076.822324316479, 1087.6736978906297, 1164.5109054197087, 18937.715934260996, 19416.90291548533, 20053.799462005485, 20056.0, 20079.899600502195, 1005.7988402319565, 1046.539522610049, 1061.2253055269696, 1062.7077553672648, 1076.0853274716585, 10749.677509674722, 11323.263987840764, 11371.31772093677, 11374.601888933848, 11400.771984560428, 474329.0, 483304.0, 488505.0, 489524.0, 490948.63577046373, 79159.0, 79781.20218797853, 80111.0, 80747.0, 80762.59618701987, 1011.7066050845173, 1041.7239431550797, 1042.358949246208, 1054.5781687325132, 1063.048571528493, 17219.13680173317, 17253.827461725723, 17386.391476298268, 17426.0, 17578.296868125133, 37610.803081737264, 39683.60316396914, 39740.0, 44151.0, 44906.77546612246, 55010.0, 55641.1576784448, 60236.69881650564, 60906.0, 61028.38971610227, 5363.544098751494, 5418.512333889968, 7927.088384835587, 8035.919640803751, 8105.311048560702, 12200.328981906081, 13500.32498375097, 13512.189268643913, 13583.0, 13762.174269543459, 11617.419129043345, 11665.59170429031, 11937.641870743892, 11941.164118511968, 12067.75864482688, 11455.828162577402, 11471.254368466356, 11471.885281147082, 11730.237534560227, 11739.530418783153, 11638.767224655629, 12203.511859525519, 12233.877661223274, 12290.877091228973, 12325.260484370967, 8030.196980301923, 8150.796230094295, 8244.051934027664, 8259.132791056853, 8356.958215208884, 11700.94149529281, 11862.762744745229, 11937.343446110548, 11984.46069926838, 12131.636050918487], "value": 9306.749033142496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.564213809443, 1476.9409223631055, 1485.331600779649, 1668.1992643531105, 1824.82664146906, 8210.384221183413, 8533.615987280571, 8575.271101956334, 8819.029906710257, 8825.646974121044, 8549.102344253857, 8667.783305417352, 8755.0, 8756.518391488466, 8759.562021898895, 1992.4321568353023, 2266.2974477911816, 2331.347222777625, 2354.482013956927, 2374.857508549484, 2366.7869891709743, 2375.10933399975, 2376.4296568823443, 2377.0610608809598, 2382.142428725666, 2323.7908588227056, 2337.4857531343164, 2366.763323667628, 2373.46597015671, 2376.9762302376926, 1967.8622496425255, 2189.813865821407, 2246.067881829039, 2254.2448279826112, 2259.6271615183473, 2289.759575244592, 2305.216226482987, 2311.39903625057, 2313.641385585237, 2314.1437668062927, 8684.956575217084, 8742.213200811893, 8743.12568743127, 8747.16901894319, 8755.25580325673, 4928.950710492921, 5005.89988200234, 5021.0, 5065.088284108859, 5070.138076527, 3152.8423578820966, 3234.450143475591, 3336.466165413522, 3357.7985320880825, 3364.899053028413, 1091.1052936591998, 1121.5681962444437, 1135.73310272086, 1143.982840257397, 1148.9253198542067, 11850.525978960832, 11863.881361186364, 11882.881171188264, 11891.940540297242, 11893.108016898681, 4345.391645169679, 4437.534058923831, 4519.231730605805, 4545.068261006486, 4551.340055691906, 10272.486375681186, 10277.434741089312, 10303.536340864679, 10305.690829275134, 10309.381437113798, 5068.417132029864, 5087.26234695967, 5088.109580823341, 5094.465081166424, 5097.617678674114, 4009.959900401017, 4512.120136573402, 4654.86035418938, 4666.580007799246, 4735.266033764772, 3904.2191561687778, 3977.960220397817, 4029.6373326400767, 4048.5344185418467, 4071.450354202204, 1261.2999785119348, 1275.508929062299, 1279.9808002879963, 1295.352323838081, 1300.7073408483016, 1005.2058873489862, 1006.3157053203745, 1019.90820826126, 1021.9233557483218, 1024.4928760263717, 5756.136579513023, 6082.239720034996, 6137.693115344215, 6216.471599913967, 6225.3774622537385, 473.90521895621015, 484.0730002046113, 494.3672099712374, 498.48655884439086, 498.51145877039977, 4064.634182923492, 4064.8170832312617, 4104.138130992525, 4123.381492776048, 4150.169966006811, 10310.123639490726, 10609.363438193734, 10623.20325975555, 10675.199360048027, 10700.571977120984, 10561.524731387106, 10583.417912014913, 10595.470226488645, 10603.734906627396, 10656.57373705043, 7739.071311442551, 8022.75931722061, 8038.799030024296, 8104.298097399612, 8105.0, 5961.976433310263, 6002.669853157943, 6095.542834287357, 6134.0, 6196.876062478816, 10071.446070466194, 10252.58989640406, 10266.435346056038, 10276.22928280367, 10278.537465813906, 9926.702198934043, 10209.642662506776, 10374.118199953089, 10379.169666426818, 10412.635557755442, 6906.102206713095, 7527.2849079337375, 7583.810404739926, 7710.614469276402, 7772.689092436465, 8588.184122508352, 8603.05366409707, 8625.051244362989, 8659.913400865911, 8667.869981950402, 7482.915547968015, 7774.144844067261, 7811.281518066016, 7827.804304892423, 7904.328132108835, 7481.738139165103, 7538.623068846646, 7538.698452061856, 7641.465097443348, 7766.417518686008, 7026.508144429841, 7131.393831524379, 7144.1784194818265, 7150.249223831423, 7160.856782864209, 5128.692278463304, 5206.817761378334, 5298.072837253389, 5321.54766844807, 5377.327834020747, 5160.432352441153, 5377.973110134424, 5487.259220005408, 5521.69630670317, 5610.410906854885], "value": 4534.7275418337385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.51426574473925, 329.71479670085375, 334.02631329674, 336.41631768881, 339.9167204035011, 5224.68651880887, 5264.420913699495, 5290.709011004393, 5293.179557168635, 5315.548178404831, 4986.900261994758, 5070.923936140952, 5103.413107492639, 5150.871228219296, 5287.947120528794, 3946.703997200207, 4002.899927501807, 4073.7555746655294, 4120.896977575555, 4149.792510374485, 4059.9594004060023, 4075.347944328908, 4078.632923036926, 4117.9176416471655, 4141.917161656765, 3855.306044911915, 4058.715889887709, 4083.183363327332, 4092.6930480213955, 4096.4265002899465, 4024.215278020787, 4035.636792688643, 4036.667899593127, 4054.0, 4078.062045729469, 3569.375359312136, 3596.1549035976523, 3605.5853576838736, 3619.059044648384, 3622.9275414491826, 4832.251001094836, 5140.948590514085, 5161.922571161435, 5162.92255616166, 5169.405518365398, 4633.976830115862, 4646.535346465326, 4666.786635474767, 4666.859994200179, 4680.7191568506, 5456.809011684572, 5470.261514695545, 5496.725163741837, 5500.202470641774, 5502.8349149525175, 3857.691384689246, 3942.48747662802, 3945.6646185074014, 3948.5261768587666, 3969.940450893239, 5651.660900345952, 5655.802046928337, 5694.629849059798, 5702.885942281131, 5719.28508936383, 3985.561588225293, 3991.1020020495157, 4003.3594624859884, 4005.1989602079116, 4012.498437695288, 4057.837686492507, 4059.4316795648633, 4065.2682517146604, 4080.3471444568727, 4088.161926805057, 4567.337736028324, 4621.468531118964, 4628.259478483426, 4628.352030715702, 4649.837255696035, 5475.589330800206, 5490.227435145226, 5492.176173573996, 5496.615236933376, 5517.565432987452, 1315.224017829485, 1363.2093385836192, 1372.4921778941655, 1405.5150972914328, 1410.978835317471, 881.6870011146079, 893.5264309915755, 897.6050537763364, 900.7793090692691, 911.694582314932, 4656.301554766745, 4707.328898241143, 4728.092223282725, 4728.692634978715, 4735.408073990751, 4692.53074692528, 4873.0, 4935.679180853233, 5022.196448568212, 5024.095662780662, 4666.8133274668635, 4687.859364219079, 4704.223803072455, 4719.221328480831, 4778.713277203379, 2649.2582077018465, 2710.3630646797988, 2727.277271523048, 2732.617433559303, 2732.8496932668495, 5054.267131265946, 5222.294990176353, 5239.423663397023, 5241.947580524146, 5270.130428479335, 4906.386701662293, 5116.846494605168, 5129.204973229155, 5139.715071232192, 5147.176451767774, 5155.871103222449, 5170.767315470813, 5172.508611681884, 5221.947780522222, 5224.093205979866, 4822.9758851205515, 4944.950550494449, 4968.975155124202, 4987.825426110068, 5068.315777370155, 5012.523810238022, 5023.773930173078, 5032.0690672225755, 5039.740064983754, 5051.419086805065, 4864.95135048645, 5003.098822447447, 5088.592912566912, 5196.740162991911, 5207.2449494823795, 3518.437050071976, 3572.7670206121174, 3601.657842504958, 3638.2723455309574, 3652.4886515887265, 5001.699898006132, 5028.949710502849, 5068.428787076018, 5127.282180494808, 5129.8717532062, 4984.352034235526, 4988.27669987857, 5007.519773757349, 5022.81051532287, 5034.949650503448, 4638.6752927294765, 4927.704337739747, 4982.6512144149565, 5276.709780962084, 5328.280682108021, 5340.839774806762, 5343.599230057839, 5363.115086010765, 5372.785088596569, 5392.865178370572, 5481.698506582176, 5491.615586909038, 5511.4213007633625, 5555.694436806015, 5556.91664625039, 5199.778052157815, 5348.277982472472, 5445.918311225256, 5464.672119672832, 5480.424555421783], "value": 4004.189345184657, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.836136050069, 773.1263672050582, 781.19564677235, 811.1118325433652, 837.225566351125, 14955.775663365059, 15516.0, 15765.921170394146, 16140.83859161408, 16896.324147034105, 16436.342546298165, 16474.588135296624, 16540.33838646456, 16567.171641417943, 16585.0, 680.581442412915, 817.3951276055706, 829.3448175940999, 831.7712629027005, 831.7795784117213, 791.8139237279264, 810.9026916769966, 821.8972628421448, 822.7901885019312, 823.7075838077486, 852.2500199824146, 853.871919212117, 859.3855393393723, 859.5057841740993, 859.7721603774974, 713.5718568858695, 823.8475881961797, 855.2174760094506, 855.6876739989921, 856.0754385263931, 619.9659018753966, 620.3579295429209, 620.4168082002902, 620.6679426506797, 620.9534284928649, 15762.0, 15811.446599368966, 15811.841881581153, 15815.446459373867, 15824.841751582451, 13992.370343334473, 14295.499657511937, 14321.856781432156, 14324.57026289213, 14345.139291642536, 13423.865761342358, 13494.257815820127, 13555.0, 13694.452221911211, 13709.17744935297, 4428.822847086113, 4429.867103986886, 4433.645308375322, 4462.977685111586, 4637.281221410687, 79240.0, 79681.80477292788, 80255.0, 80852.0, 80965.19034809695, 10605.787884242272, 10874.075703565128, 10904.964028417287, 10961.12311015117, 11188.832167517494, 21395.89302053511, 21539.2461263855, 21550.676739848914, 21662.89168554147, 21960.89019554924, 8308.5430301334, 8342.582870856433, 8374.204450577185, 8426.53654049021, 8441.324694024463, 4547.113312903952, 4599.632029437704, 4670.346151538787, 4685.531446855287, 4702.952970470252, 3432.6567343265474, 3552.1297282166033, 3559.715222782171, 3563.893083207508, 3564.500969864221, 594.8126340202822, 596.0760820727941, 604.4076804731388, 617.8949578571653, 622.5362105231632, 566.8214512428572, 667.7195577857311, 668.9933100668931, 669.6986356139756, 703.1878180701298, 9414.340996130342, 10105.646302379382, 10133.08802207805, 10136.797264054678, 10138.645147419953, 671.2414971082743, 680.7549282258383, 682.8429461223947, 683.0573808144828, 693.6046453521545, 4488.887777805581, 4705.835295764632, 4796.103228511131, 4823.0, 4910.361652985161, 91250.0, 110511.44744276228, 120195.0, 121177.0, 125765.37117314355, 84603.0, 92494.0, 93636.0, 95364.0, 95937.0, 53334.46665533295, 53802.157805797666, 60728.0, 77972.83040754394, 87495.56252218699, 9975.746491193113, 10290.485475726333, 10337.224708146769, 10406.791864162826, 10408.947955260175, 50257.74871125621, 50492.0, 51751.0, 51829.48170518397, 53629.0, 65816.6709166451, 66774.0, 70647.64676176586, 73135.63432183018, 73614.26385736288, 13004.0, 13051.477940882638, 13196.538121165713, 13554.796678050034, 13666.658333541742, 14744.705105898036, 15192.772108418603, 15314.770278445609, 15325.233738312816, 15868.365265389717, 12275.93862030684, 12345.876541234473, 12360.01119910423, 13027.348632568523, 14670.486532971296, 13733.519326823513, 14017.299135043411, 14242.28788560547, 14295.64260893486, 14333.713325733635, 13833.170009799444, 14323.212223327817, 14464.276786160439, 14492.710145797237, 14534.636634084232, 11412.372319522507, 11473.655790326304, 11711.941440292743, 11831.0, 11874.16880818369, 11115.0, 11163.832542511705, 11755.471003804678, 12181.69545761363, 12240.816387754012], "value": 7600.200256401817, "unit": "score"}], "name": "dromaeo_css", "value": 7208.280786396113, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 110.0, 111.0, 110.0, 110.0, 111.0, 112.0, 111.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 133.0, 127.0, 180.0, 126.0, 125.0, 157.0, 125.0, 157.0, 127.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 161.0, 160.0, 161.0, 162.0, 163.0, 161.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 84.0, 122.0, 75.0, 76.0, 74.0, 74.0, 102.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 124.0, 85.0, 84.0, 85.0, 113.0, 85.0, 85.0, 141.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 52.0, 51.0, 51.0, 53.0, 54.0, 53.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 61.0, 60.0, 59.0, 61.0, 60.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 82.0, 85.0, 81.0, 81.0, 86.0, 81.0, 111.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 129.0, 127.0, 130.0, 126.0, 120.0, 129.0, 130.0, 135.0, 139.0], "value": 129.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 162.0, 163.0, 162.0, 159.0, 160.0, 158.0, 162.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 66.0, 62.0, 61.0, 65.0, 62.0, 65.0, 62.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1502.8, "alertThreshold": 2.0}]} 23:37:54 INFO - Return code: 0 23:37:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:37:54 ERROR - # TBPL SUCCESS # 23:37:54 INFO - Running post-action listener: _resource_record_post_action 23:37:54 INFO - ##### 23:37:54 INFO - ##### Finished run-tests step (success) 23:37:54 INFO - ##### 23:37:54 INFO - Running post-run listener: _resource_record_post_run 23:37:54 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 542806016; Read time: 176; Write time: 424860 23:37:54 INFO - TinderboxPrint: CPU usage
13.2% 23:37:54 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 176 23:37:54 INFO - TinderboxPrint: I/O write bytes / time
542,806,016 / 424,860 23:37:54 INFO - TinderboxPrint: CPU idle
8,245.2 (86.7%) 23:37:54 INFO - TinderboxPrint: CPU user
1,218.3 (12.8%) 23:37:54 INFO - TinderboxPrint: Swap in / out
0 / 0 23:37:54 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247844864; Read time: 0; Write time: 235640 23:37:54 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 294961152; Read time: 176; Write time: 189220 23:37:55 INFO - Running post-run listener: _upload_blobber_files 23:37:55 INFO - Blob upload gear active. 23:37:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:37:55 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 23:37:55 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:37:55 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:37:55 INFO - (blobuploader) - INFO - Open directory for files ... 23:37:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:37:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:56 INFO - (blobuploader) - INFO - Done attempting. 23:37:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:37:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:37:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:57 INFO - (blobuploader) - INFO - Done attempting. 23:37:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:37:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:38:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:01 INFO - (blobuploader) - INFO - Done attempting. 23:38:01 INFO - (blobuploader) - INFO - Iteration through files over. 23:38:01 INFO - Return code: 0 23:38:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:38:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:38:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bf9732d37972d7c1e8729cb05c49dbcb2a3e2db5c091cfc740941ccf767da79e024f6088c309bc22bef455203cf03327b18ed3aec14d6ba37d57c50cc1c627a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0411fdbc468cce464a99ef2ace9db9b8b71c61122d768908934abc8614ec5a8796554e6f99e45950c6a500db7a89a263dcc78409b20f200df42092dade31fae6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2753fafef2b0a1969e1c29e0ce80fe0e70bfa18c372eeb27f39d17307657686cfaee5753de0c2c17386c202237ef9923db560628eaeca42fa83ec16b0181d51"} 23:38:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:38:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:38:01 INFO - Contents: 23:38:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bf9732d37972d7c1e8729cb05c49dbcb2a3e2db5c091cfc740941ccf767da79e024f6088c309bc22bef455203cf03327b18ed3aec14d6ba37d57c50cc1c627a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0411fdbc468cce464a99ef2ace9db9b8b71c61122d768908934abc8614ec5a8796554e6f99e45950c6a500db7a89a263dcc78409b20f200df42092dade31fae6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2753fafef2b0a1969e1c29e0ce80fe0e70bfa18c372eeb27f39d17307657686cfaee5753de0c2c17386c202237ef9923db560628eaeca42fa83ec16b0181d51"} 23:38:01 INFO - Running post-run listener: copy_logs_to_upload_dir 23:38:01 INFO - Copying logs to upload dir... 23:38:01 INFO - mkdir: /builds/slave/test/build/upload/logs 23:38:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.931564 ========= master_lag: 222.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 17 secs) (at 2016-06-06 23:41:43.716984) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 23:41:43.720907) ========= 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=0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bf9732d37972d7c1e8729cb05c49dbcb2a3e2db5c091cfc740941ccf767da79e024f6088c309bc22bef455203cf03327b18ed3aec14d6ba37d57c50cc1c627a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0411fdbc468cce464a99ef2ace9db9b8b71c61122d768908934abc8614ec5a8796554e6f99e45950c6a500db7a89a263dcc78409b20f200df42092dade31fae6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2753fafef2b0a1969e1c29e0ce80fe0e70bfa18c372eeb27f39d17307657686cfaee5753de0c2c17386c202237ef9923db560628eaeca42fa83ec16b0181d51"} build_url:https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/WBJUxZ5EQKaUaXv1CnNN-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4bf9732d37972d7c1e8729cb05c49dbcb2a3e2db5c091cfc740941ccf767da79e024f6088c309bc22bef455203cf03327b18ed3aec14d6ba37d57c50cc1c627a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0411fdbc468cce464a99ef2ace9db9b8b71c61122d768908934abc8614ec5a8796554e6f99e45950c6a500db7a89a263dcc78409b20f200df42092dade31fae6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f2753fafef2b0a1969e1c29e0ce80fe0e70bfa18c372eeb27f39d17307657686cfaee5753de0c2c17386c202237ef9923db560628eaeca42fa83ec16b0181d51"}' ========= master_lag: 0.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 23:41:44.199514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:41:44.199843) ========= 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=0333a256a5e4f052382dd180000001b1-1465280196.62324-528840708 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.56 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:41:44.763956) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 23:41:44.764229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 23:41:45.413046) ========= ========= Total master_lag: 223.39 =========