builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1465279705.68 results: success (0) buildid: 20160606203027 builduid: 1a213dc7dff44e669decd9e6e96a8bca revision: 580436387d62e0a40fcc781832c3291858121816 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.676207) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.676621) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.676892) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.693375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.693654) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 23:08: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% 225M=0s 2016-06-06 23:08:25 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088830 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.870583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.871132) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017162 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.905521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:25.905841) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 23:08:25,935 truncating revision to first 12 chars 2016-06-06 23:08:25,935 Setting DEBUG logging. 2016-06-06 23:08:25,935 attempt 1/10 2016-06-06 23:08:25,935 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:08:26,244 unpacking tar archive at: fx-team-580436387d62/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627425 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:26.548450) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:26.548810) ========= script_repo_revision: 580436387d62e0a40fcc781832c3291858121816 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:26.549508) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:26.549777) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 23:08:26.560813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-06 23:08:26.561105) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:08:26 INFO - MultiFileLogger online at 20160606 23:08:26 in /builds/slave/test 23:08:26 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:08:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:08:26 INFO - {'append_to_log': False, 23:08:26 INFO - 'base_work_dir': '/builds/slave/test', 23:08:26 INFO - 'blob_upload_branch': 'Fx-Team', 23:08:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:08:26 INFO - 'branch': 'Fx-Team', 23:08:26 INFO - 'buildbot_json_path': 'buildprops.json', 23:08:26 INFO - 'config_files': ('talos/linux_config.py',), 23:08:26 INFO - 'default_actions': ('clobber', 23:08:26 INFO - 'read-buildbot-config', 23:08:26 INFO - 'download-and-extract', 23:08:26 INFO - 'populate-webroot', 23:08:26 INFO - 'create-virtualenv', 23:08:26 INFO - 'install', 23:08:26 INFO - 'run-tests'), 23:08:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:08:26 INFO - 'download_minidump_stackwalk': True, 23:08:26 INFO - 'download_symbols': 'ondemand', 23:08:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:08:26 INFO - 'tooltool.py': '/tools/tooltool.py', 23:08:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:08:26 INFO - '/tools/misc-python/virtualenv.py')}, 23:08:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:08:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:08:26 INFO - 'installer_path': 'installer.exe', 23:08:26 INFO - 'log_level': 'info', 23:08:26 INFO - 'log_name': 'talos', 23:08:26 INFO - 'log_to_console': True, 23:08:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:08:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:08:26 INFO - 'opt_config_files': (), 23:08:26 INFO - 'pip_index': False, 23:08:26 INFO - 'sps_profile': False, 23:08:26 INFO - 'sps_profile_interval': 0, 23:08:26 INFO - 'suite': 'dromaeojs', 23:08:26 INFO - 'system_bits': '32', 23:08:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:08:26 INFO - 'title': 'talos-linux64-ix-006', 23:08:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:08:26 INFO - 'use_talos_json': True, 23:08:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:08:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:08:26 INFO - 'work_dir': 'build'} 23:08:26 INFO - ##### 23:08:26 INFO - ##### Running clobber step. 23:08:26 INFO - ##### 23:08:26 INFO - Running pre-action listener: _resource_record_pre_action 23:08:26 INFO - Running main action method: clobber 23:08:26 INFO - rmtree: /builds/slave/test/build 23:08:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:08:27 INFO - Running post-action listener: _resource_record_post_action 23:08:27 INFO - ##### 23:08:27 INFO - ##### Finished clobber step (success) 23:08:27 INFO - ##### 23:08:27 INFO - ##### 23:08:27 INFO - ##### Running read-buildbot-config step. 23:08:27 INFO - ##### 23:08:27 INFO - Running pre-action listener: _resource_record_pre_action 23:08:27 INFO - Running main action method: read_buildbot_config 23:08:27 INFO - Using buildbot properties: 23:08:27 INFO - { 23:08:27 INFO - "project": "", 23:08:27 INFO - "product": "firefox", 23:08:27 INFO - "script_repo_revision": "production", 23:08:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:08:27 INFO - "repository": "", 23:08:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:08:27 INFO - "buildid": "20160606203027", 23:08:27 INFO - "pgo_build": "True", 23:08:27 INFO - "basedir": "/builds/slave/test", 23:08:27 INFO - "buildnumber": 165, 23:08:27 INFO - "slavename": "talos-linux64-ix-006", 23:08:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:08:27 INFO - "platform": "ubuntu64_hw", 23:08:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:08:27 INFO - "branch": "fx-team", 23:08:27 INFO - "repo_path": "integration/fx-team", 23:08:27 INFO - "revision": "580436387d62e0a40fcc781832c3291858121816", 23:08:27 INFO - "stage_platform": "linux64-pgo", 23:08:27 INFO - "builduid": "1a213dc7dff44e669decd9e6e96a8bca", 23:08:27 INFO - "slavebuilddir": "test" 23:08:27 INFO - } 23:08: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:08:27 INFO - Running post-action listener: _resource_record_post_action 23:08:27 INFO - ##### 23:08:27 INFO - ##### Finished read-buildbot-config step (success) 23:08:27 INFO - ##### 23:08:27 INFO - ##### 23:08:27 INFO - ##### Running download-and-extract step. 23:08:27 INFO - ##### 23:08:27 INFO - Running pre-action listener: _resource_record_pre_action 23:08:27 INFO - Running main action method: download_and_extract 23:08:27 INFO - mkdir: /builds/slave/test/build/tests 23:08: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:08: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:08: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:08: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:08: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:08: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:08:34 INFO - Downloaded 1361 bytes. 23:08:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:08:34 INFO - Using the following test package requirements: 23:08:34 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:08:34 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:08:34 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:08:34 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'jsshell-linux-x86_64.zip'], 23:08:34 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:08:34 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:08:34 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:08:34 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:08:34 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:08:34 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:34 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:08:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:08:34 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:08:34 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:08:34 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:08:34 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:08:34 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:08:34 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:08:36 INFO - Downloaded 24443563 bytes. 23:08:36 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:08:36 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:08:38 INFO - Return code: 0 23:08:38 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:08:38 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:08:38 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:08:38 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:08:38 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:08:38 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:08:38 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:08:40 INFO - Downloaded 24443563 bytes. 23:08:40 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:08:40 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:08:41 INFO - Return code: 0 23:08:41 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:08:41 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:08:41 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:08:41 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:08:41 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:08:41 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:08:42 INFO - Downloaded 10833369 bytes. 23:08:42 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:08:42 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:08:42 INFO - Return code: 0 23:08: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:08:42 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:08:42 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:08:42 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:08:42 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:08:42 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:08:44 INFO - Downloaded 59195570 bytes. 23:08:44 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:08:44 INFO - mkdir: /builds/slave/test/properties 23:08:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:08:44 INFO - Writing to file /builds/slave/test/properties/build_url 23:08:44 INFO - Contents: 23:08:44 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:08:45 INFO - Running post-action listener: _resource_record_post_action 23:08:45 INFO - Running post-action listener: set_extra_try_arguments 23:08:45 INFO - ##### 23:08:45 INFO - ##### Finished download-and-extract step (success) 23:08:45 INFO - ##### 23:08:45 INFO - ##### 23:08:45 INFO - ##### Running populate-webroot step. 23:08:45 INFO - ##### 23:08:45 INFO - Running pre-action listener: _resource_record_pre_action 23:08:45 INFO - Running main action method: populate_webroot 23:08:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:08:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:08:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:08:45 INFO - '--tppagecycles', 23:08:45 INFO - '7'], 23:08:45 INFO - 'tests': ['tsvgm']}}, 23:08:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:08:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:08:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:08:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:08:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:08:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:08:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['damp', 'tps']}, 23:08:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'tests': ['damp', 'tps']}, 23:08:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['dromaeo_dom']}, 23:08:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:08:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['a11yr', 23:08:45 INFO - 'ts_paint', 23:08:45 INFO - 'tpaint', 23:08:45 INFO - 'sessionrestore', 23:08:45 INFO - 'sessionrestore_no_auto_restore', 23:08:45 INFO - 'tabpaint']}, 23:08:45 INFO - 'other-e10s': {'tests': ['a11yr', 23:08:45 INFO - 'ts_paint', 23:08:45 INFO - 'tpaint', 23:08:45 INFO - 'sessionrestore', 23:08:45 INFO - 'sessionrestore_no_auto_restore', 23:08:45 INFO - 'tabpaint']}, 23:08:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:08:45 INFO - 'ts_paint', 23:08:45 INFO - 'tpaint', 23:08:45 INFO - 'sessionrestore', 23:08:45 INFO - 'sessionrestore_no_auto_restore', 23:08:45 INFO - 'tabpaint']}, 23:08:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:08:45 INFO - 'ts_paint', 23:08:45 INFO - 'tpaint', 23:08:45 INFO - 'sessionrestore', 23:08:45 INFO - 'sessionrestore_no_auto_restore', 23:08:45 INFO - 'tabpaint']}, 23:08:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['a11yr', 23:08:45 INFO - 'ts_paint', 23:08:45 INFO - 'tpaint', 23:08:45 INFO - 'sessionrestore', 23:08:45 INFO - 'sessionrestore_no_auto_restore', 23:08:45 INFO - 'tabpaint']}, 23:08:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['a11yr', 23:08:45 INFO - 'ts_paint', 23:08:45 INFO - 'tpaint', 23:08:45 INFO - 'sessionrestore', 23:08:45 INFO - 'sessionrestore_no_auto_restore', 23:08:45 INFO - 'tabpaint']}, 23:08:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['tsvgx', 23:08:45 INFO - 'tsvgr_opacity', 23:08:45 INFO - 'tart', 23:08:45 INFO - 'tscrollx', 23:08:45 INFO - 'cart']}, 23:08:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:08:45 INFO - 'tsvgr_opacity', 23:08:45 INFO - 'tart', 23:08:45 INFO - 'tscrollx', 23:08:45 INFO - 'cart']}, 23:08:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'talos_options': ['--disable-e10s'], 23:08:45 INFO - 'tests': ['tp5o']}, 23:08:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'tests': ['tp5o']}, 23:08:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'talos_options': ['--disable-e10s', 23:08:45 INFO - '--xperf_path', 23:08:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:45 INFO - 'tests': ['tp5n']}, 23:08:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:45 INFO - 'talos_options': ['--xperf_path', 23:08:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:45 INFO - 'tests': ['tp5n']}}, 23:08:45 INFO - 'talos.zip': {'path': '', 23:08:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:08:45 INFO - Running post-action listener: _resource_record_post_action 23:08:45 INFO - ##### 23:08:45 INFO - ##### Finished populate-webroot step (success) 23:08:45 INFO - ##### 23:08:45 INFO - ##### 23:08:45 INFO - ##### Running create-virtualenv step. 23:08:45 INFO - ##### 23:08:45 INFO - Running pre-action listener: _resource_record_pre_action 23:08:45 INFO - Running main action method: create_virtualenv 23:08:45 INFO - Creating virtualenv /builds/slave/test/build/venv 23:08:45 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:08:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:08:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:08:45 INFO - Using real prefix '/usr' 23:08:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:08:45 INFO - Installing distribute.............................................................................................................................................................................................done. 23:08:47 INFO - Installing pip.................done. 23:08:47 INFO - Return code: 0 23:08:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:08: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:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08: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:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:08: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', 'pip>=1.5'] in /builds/slave/test/build 23:08: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 pip>=1.5 23:08:47 INFO - Using env: {'DISPLAY': ':0', 23:08:47 INFO - 'HOME': '/home/cltbld', 23:08:47 INFO - 'LANG': 'en_US.UTF-8', 23:08:47 INFO - 'LANGUAGE': 'en_US:en', 23:08:47 INFO - 'LOGNAME': 'cltbld', 23:08:47 INFO - 'MAIL': '/var/mail/cltbld', 23:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:47 INFO - 'MOZ_NO_REMOTE': '1', 23:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:47 INFO - 'NO_EM_RESTART': '1', 23:08:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:47 INFO - 'PWD': '/builds/slave/test', 23:08:47 INFO - 'SHELL': '/bin/bash', 23:08:47 INFO - 'SHLVL': '1', 23:08:47 INFO - 'TERM': 'linux', 23:08:47 INFO - 'TMOUT': '86400', 23:08:47 INFO - 'USER': 'cltbld', 23:08:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:47 INFO - '_': '/tools/buildbot/bin/python'} 23:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:48 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:08:48 INFO - Cleaning up... 23:08:48 INFO - Return code: 0 23:08:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:08:48 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:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:48 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:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:48 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:08:48 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:08:48 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:08:48 INFO - Using env: {'DISPLAY': ':0', 23:08:48 INFO - 'HOME': '/home/cltbld', 23:08:48 INFO - 'LANG': 'en_US.UTF-8', 23:08:48 INFO - 'LANGUAGE': 'en_US:en', 23:08:48 INFO - 'LOGNAME': 'cltbld', 23:08:48 INFO - 'MAIL': '/var/mail/cltbld', 23:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:48 INFO - 'MOZ_NO_REMOTE': '1', 23:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:48 INFO - 'NO_EM_RESTART': '1', 23:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:48 INFO - 'PWD': '/builds/slave/test', 23:08:48 INFO - 'SHELL': '/bin/bash', 23:08:48 INFO - 'SHLVL': '1', 23:08:48 INFO - 'TERM': 'linux', 23:08:48 INFO - 'TMOUT': '86400', 23:08:48 INFO - 'USER': 'cltbld', 23:08:48 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:48 INFO - '_': '/tools/buildbot/bin/python'} 23:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:48 INFO - Downloading/unpacking psutil>=3.1.1 23:08:48 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:08:48 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:08:48 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:08:48 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:08:48 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:08:48 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:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:08:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:08:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:08:50 INFO - Installing collected packages: psutil 23:08:50 INFO - Running setup.py install for psutil 23:08:50 INFO - building 'psutil._psutil_linux' extension 23:08:50 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:08:50 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:08:50 INFO - building 'psutil._psutil_posix' extension 23:08:50 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:08:50 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:08:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:08:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:08:50 INFO - Successfully installed psutil 23:08:50 INFO - Cleaning up... 23:08:50 INFO - Return code: 0 23:08:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:08:50 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:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:50 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:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:50 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:08:50 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:08:50 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:08:50 INFO - Using env: {'DISPLAY': ':0', 23:08:50 INFO - 'HOME': '/home/cltbld', 23:08:50 INFO - 'LANG': 'en_US.UTF-8', 23:08:50 INFO - 'LANGUAGE': 'en_US:en', 23:08:50 INFO - 'LOGNAME': 'cltbld', 23:08:50 INFO - 'MAIL': '/var/mail/cltbld', 23:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:50 INFO - 'MOZ_NO_REMOTE': '1', 23:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:51 INFO - 'NO_EM_RESTART': '1', 23:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:51 INFO - 'PWD': '/builds/slave/test', 23:08:51 INFO - 'SHELL': '/bin/bash', 23:08:51 INFO - 'SHLVL': '1', 23:08:51 INFO - 'TERM': 'linux', 23:08:51 INFO - 'TMOUT': '86400', 23:08:51 INFO - 'USER': 'cltbld', 23:08:51 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:51 INFO - '_': '/tools/buildbot/bin/python'} 23:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:08: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:08: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:08: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:08: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:08: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:08: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:08:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:08:53 INFO - Installing collected packages: mozsystemmonitor 23:08:53 INFO - Running setup.py install for mozsystemmonitor 23:08:53 INFO - Successfully installed mozsystemmonitor 23:08:53 INFO - Cleaning up... 23:08:53 INFO - Return code: 0 23:08:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:08: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:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08: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:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:53 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:08:53 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:08:53 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:08:53 INFO - Using env: {'DISPLAY': ':0', 23:08:53 INFO - 'HOME': '/home/cltbld', 23:08:53 INFO - 'LANG': 'en_US.UTF-8', 23:08:53 INFO - 'LANGUAGE': 'en_US:en', 23:08:53 INFO - 'LOGNAME': 'cltbld', 23:08:53 INFO - 'MAIL': '/var/mail/cltbld', 23:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:53 INFO - 'MOZ_NO_REMOTE': '1', 23:08:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:53 INFO - 'NO_EM_RESTART': '1', 23:08:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:53 INFO - 'PWD': '/builds/slave/test', 23:08:53 INFO - 'SHELL': '/bin/bash', 23:08:53 INFO - 'SHLVL': '1', 23:08:53 INFO - 'TERM': 'linux', 23:08:53 INFO - 'TMOUT': '86400', 23:08:53 INFO - 'USER': 'cltbld', 23:08:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:53 INFO - '_': '/tools/buildbot/bin/python'} 23:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:53 INFO - Downloading/unpacking jsonschema==2.5.1 23:08:53 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:08:53 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:08:53 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:08:53 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:08:53 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:08:53 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:08:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:08:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:08:55 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:08:55 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:08:55 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:08:55 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:08:55 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:08:55 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:08:55 INFO - Downloading functools32-3.2.3-2.tar.gz 23:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:08:55 INFO - warning: no files found matching '*.txt' 23:08:55 INFO - no previously-included directories found matching 'build' 23:08:55 INFO - no previously-included directories found matching 'dist' 23:08:55 INFO - no previously-included directories found matching '.git*' 23:08:55 INFO - Installing collected packages: jsonschema, functools32 23:08:55 INFO - Running setup.py install for functools32 23:08:55 INFO - warning: no files found matching '*.txt' 23:08:55 INFO - no previously-included directories found matching 'build' 23:08:55 INFO - no previously-included directories found matching 'dist' 23:08:55 INFO - no previously-included directories found matching '.git*' 23:08:55 INFO - Successfully installed jsonschema functools32 23:08:55 INFO - Cleaning up... 23:08:55 INFO - Return code: 0 23:08:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:08:55 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:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:55 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:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:55 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:08:55 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:08:55 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:08:55 INFO - Using env: {'DISPLAY': ':0', 23:08:55 INFO - 'HOME': '/home/cltbld', 23:08:55 INFO - 'LANG': 'en_US.UTF-8', 23:08:55 INFO - 'LANGUAGE': 'en_US:en', 23:08:55 INFO - 'LOGNAME': 'cltbld', 23:08:55 INFO - 'MAIL': '/var/mail/cltbld', 23:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:55 INFO - 'MOZ_NO_REMOTE': '1', 23:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:55 INFO - 'NO_EM_RESTART': '1', 23:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:55 INFO - 'PWD': '/builds/slave/test', 23:08:55 INFO - 'SHELL': '/bin/bash', 23:08:55 INFO - 'SHLVL': '1', 23:08:55 INFO - 'TERM': 'linux', 23:08:55 INFO - 'TMOUT': '86400', 23:08:55 INFO - 'USER': 'cltbld', 23:08:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:55 INFO - '_': '/tools/buildbot/bin/python'} 23:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:56 INFO - Downloading/unpacking blobuploader==1.2.4 23:08:56 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:08:56 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:08:56 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:08:56 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:08:56 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:08:56 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:08:57 INFO - Downloading blobuploader-1.2.4.tar.gz 23:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:08:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:08:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 INFO - Downloading docopt-0.6.1.tar.gz 23:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:08:58 INFO - Installing collected packages: blobuploader, requests, docopt 23:08:58 INFO - Running setup.py install for blobuploader 23:08:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:08:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:08:58 INFO - Running setup.py install for requests 23:08:59 INFO - Running setup.py install for docopt 23:08:59 INFO - Successfully installed blobuploader requests docopt 23:08:59 INFO - Cleaning up... 23:08:59 INFO - Return code: 0 23:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:59 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:08:59 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:08:59 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:08:59 INFO - Using env: {'DISPLAY': ':0', 23:08:59 INFO - 'HOME': '/home/cltbld', 23:08:59 INFO - 'LANG': 'en_US.UTF-8', 23:08:59 INFO - 'LANGUAGE': 'en_US:en', 23:08:59 INFO - 'LOGNAME': 'cltbld', 23:08:59 INFO - 'MAIL': '/var/mail/cltbld', 23:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:59 INFO - 'MOZ_NO_REMOTE': '1', 23:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:59 INFO - 'NO_EM_RESTART': '1', 23:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:59 INFO - 'PWD': '/builds/slave/test', 23:08:59 INFO - 'SHELL': '/bin/bash', 23:08:59 INFO - 'SHLVL': '1', 23:08:59 INFO - 'TERM': 'linux', 23:08:59 INFO - 'TMOUT': '86400', 23:08:59 INFO - 'USER': 'cltbld', 23:08:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:59 INFO - '_': '/tools/buildbot/bin/python'} 23:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:08:59 INFO - Running setup.py (path:/tmp/pip-IhQ3NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:08:59 INFO - Running setup.py (path:/tmp/pip-t0aCfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:08:59 INFO - Running setup.py (path:/tmp/pip-E938iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:08:59 INFO - Running setup.py (path:/tmp/pip-gjrP6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:08:59 INFO - Running setup.py (path:/tmp/pip-jWfLKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:08:59 INFO - Running setup.py (path:/tmp/pip-hqKOp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:08:59 INFO - Running setup.py (path:/tmp/pip-YJKgQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:09:00 INFO - Running setup.py (path:/tmp/pip-qZnjIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:09:00 INFO - Running setup.py (path:/tmp/pip-hD4F_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:09:00 INFO - Running setup.py (path:/tmp/pip-nCU4qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:09:00 INFO - Running setup.py (path:/tmp/pip-YHTAlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:09:00 INFO - Running setup.py (path:/tmp/pip-KHcxeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:09:00 INFO - Running setup.py (path:/tmp/pip-8ISAlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:09:00 INFO - Running setup.py (path:/tmp/pip-orwLFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:00 INFO - Running setup.py (path:/tmp/pip-fWUelw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:09:00 INFO - Running setup.py (path:/tmp/pip-UPmKEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:09:00 INFO - Running setup.py (path:/tmp/pip-8sb97o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:09:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:09:00 INFO - Running setup.py install for manifestparser 23:09:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:09:00 INFO - Running setup.py install for mozcrash 23:09:00 INFO - Running setup.py install for mozdebug 23:09:01 INFO - Running setup.py install for mozdevice 23:09:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Running setup.py install for mozfile 23:09:01 INFO - Running setup.py install for mozhttpd 23:09:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Running setup.py install for mozinfo 23:09:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Running setup.py install for mozInstall 23:09:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Running setup.py install for mozleak 23:09:01 INFO - Running setup.py install for mozlog 23:09:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Running setup.py install for moznetwork 23:09:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:09:01 INFO - Running setup.py install for mozprocess 23:09:01 INFO - Running setup.py install for mozprofile 23:09:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:09:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:09:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:09:02 INFO - Running setup.py install for mozrunner 23:09:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:09:02 INFO - Running setup.py install for mozscreenshot 23:09:02 INFO - Running setup.py install for moztest 23:09:02 INFO - Running setup.py install for mozversion 23:09:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:09:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:09:02 INFO - Cleaning up... 23:09:02 INFO - Return code: 0 23:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:02 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:09:02 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:09:02 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:09:02 INFO - Using env: {'DISPLAY': ':0', 23:09:02 INFO - 'HOME': '/home/cltbld', 23:09:02 INFO - 'LANG': 'en_US.UTF-8', 23:09:02 INFO - 'LANGUAGE': 'en_US:en', 23:09:02 INFO - 'LOGNAME': 'cltbld', 23:09:02 INFO - 'MAIL': '/var/mail/cltbld', 23:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:02 INFO - 'MOZ_NO_REMOTE': '1', 23:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:02 INFO - 'NO_EM_RESTART': '1', 23:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:02 INFO - 'PWD': '/builds/slave/test', 23:09:02 INFO - 'SHELL': '/bin/bash', 23:09:02 INFO - 'SHLVL': '1', 23:09:02 INFO - 'TERM': 'linux', 23:09:02 INFO - 'TMOUT': '86400', 23:09:02 INFO - 'USER': 'cltbld', 23:09:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:02 INFO - '_': '/tools/buildbot/bin/python'} 23:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:09:02 INFO - Running setup.py (path:/tmp/pip-s71xDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:09:02 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:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:09:02 INFO - Running setup.py (path:/tmp/pip-HvMrJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:09:02 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:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:09:02 INFO - Running setup.py (path:/tmp/pip-A3clkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:09:02 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:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:09:02 INFO - Running setup.py (path:/tmp/pip-7rMwgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:09:03 INFO - Running setup.py (path:/tmp/pip-Aux8YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:09:03 INFO - Running setup.py (path:/tmp/pip-aGFUwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:09:03 INFO - Running setup.py (path:/tmp/pip-SzbsIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:09:03 INFO - Running setup.py (path:/tmp/pip-UWUqgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:09:03 INFO - Running setup.py (path:/tmp/pip-GAd0sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:09:03 INFO - Running setup.py (path:/tmp/pip-Oy8K0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:09:03 INFO - Running setup.py (path:/tmp/pip-Gvkst8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:09:03 INFO - Running setup.py (path:/tmp/pip-_zYQ7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:09:03 INFO - Running setup.py (path:/tmp/pip-dHkwfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:09:03 INFO - Running setup.py (path:/tmp/pip-3bmCtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:03 INFO - Running setup.py (path:/tmp/pip-f8jPKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:09:03 INFO - Running setup.py (path:/tmp/pip-F15FCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:09:03 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:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:09:03 INFO - Running setup.py (path:/tmp/pip-HjIT3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:09:04 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:09:04 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:09:04 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:09:04 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:09:04 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:09:04 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:09:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:05 INFO - Downloading blessings-1.6.tar.gz 23:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:09:05 INFO - Installing collected packages: blessings 23:09:05 INFO - Running setup.py install for blessings 23:09:06 INFO - Successfully installed blessings 23:09:06 INFO - Cleaning up... 23:09:06 INFO - Return code: 0 23:09:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:06 INFO - Reading from file tmpfile_stdout 23:09:06 INFO - Current package versions: 23:09:06 INFO - argparse == 1.2.1 23:09:06 INFO - blessings == 1.6 23:09:06 INFO - blobuploader == 1.2.4 23:09:06 INFO - docopt == 0.6.1 23:09:06 INFO - functools32 == 3.2.3-2 23:09:06 INFO - jsonschema == 2.5.1 23:09:06 INFO - manifestparser == 1.1 23:09:06 INFO - mozInstall == 1.12 23:09:06 INFO - mozcrash == 0.17 23:09:06 INFO - mozdebug == 0.1 23:09:06 INFO - mozdevice == 0.48 23:09:06 INFO - mozfile == 1.2 23:09:06 INFO - mozhttpd == 0.7 23:09:06 INFO - mozinfo == 0.9 23:09:06 INFO - mozleak == 0.1 23:09:06 INFO - mozlog == 3.2 23:09:06 INFO - moznetwork == 0.27 23:09:06 INFO - mozprocess == 0.22 23:09:06 INFO - mozprofile == 0.28 23:09:06 INFO - mozrunner == 6.11 23:09:06 INFO - mozscreenshot == 0.1 23:09:06 INFO - mozsystemmonitor == 0.3 23:09:06 INFO - moztest == 0.7 23:09:06 INFO - mozversion == 1.4 23:09:06 INFO - psutil == 3.1.1 23:09:06 INFO - requests == 1.2.3 23:09:06 INFO - wsgiref == 0.1.2 23:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:06 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:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:06 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:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:06 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:09:06 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:09:06 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:09:06 INFO - Using env: {'DISPLAY': ':0', 23:09:06 INFO - 'HOME': '/home/cltbld', 23:09:06 INFO - 'LANG': 'en_US.UTF-8', 23:09:06 INFO - 'LANGUAGE': 'en_US:en', 23:09:06 INFO - 'LOGNAME': 'cltbld', 23:09:06 INFO - 'MAIL': '/var/mail/cltbld', 23:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:06 INFO - 'MOZ_NO_REMOTE': '1', 23:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:06 INFO - 'NO_EM_RESTART': '1', 23:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:06 INFO - 'PWD': '/builds/slave/test', 23:09:06 INFO - 'SHELL': '/bin/bash', 23:09:06 INFO - 'SHLVL': '1', 23:09:06 INFO - 'TERM': 'linux', 23:09:06 INFO - 'TMOUT': '86400', 23:09:06 INFO - 'USER': 'cltbld', 23:09:06 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:06 INFO - '_': '/tools/buildbot/bin/python'} 23:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 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:09:06 INFO - Cleaning up... 23:09:06 INFO - Return code: 0 23:09:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:09:06 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:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:06 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:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:06 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:09:06 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:09:06 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:09:06 INFO - Using env: {'DISPLAY': ':0', 23:09:06 INFO - 'HOME': '/home/cltbld', 23:09:06 INFO - 'LANG': 'en_US.UTF-8', 23:09:06 INFO - 'LANGUAGE': 'en_US:en', 23:09:06 INFO - 'LOGNAME': 'cltbld', 23:09:06 INFO - 'MAIL': '/var/mail/cltbld', 23:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:06 INFO - 'MOZ_NO_REMOTE': '1', 23:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:06 INFO - 'NO_EM_RESTART': '1', 23:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:06 INFO - 'PWD': '/builds/slave/test', 23:09:06 INFO - 'SHELL': '/bin/bash', 23:09:06 INFO - 'SHLVL': '1', 23:09:06 INFO - 'TERM': 'linux', 23:09:06 INFO - 'TMOUT': '86400', 23:09:06 INFO - 'USER': 'cltbld', 23:09:06 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:06 INFO - '_': '/tools/buildbot/bin/python'} 23:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:09:06 INFO - Cleaning up... 23:09:06 INFO - Return code: 0 23:09:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:09:06 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:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:06 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:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:06 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 0x7f3e2ed21f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b7f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', '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:09:06 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:09:06 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:09:06 INFO - Using env: {'DISPLAY': ':0', 23:09:06 INFO - 'HOME': '/home/cltbld', 23:09:06 INFO - 'LANG': 'en_US.UTF-8', 23:09:06 INFO - 'LANGUAGE': 'en_US:en', 23:09:06 INFO - 'LOGNAME': 'cltbld', 23:09:06 INFO - 'MAIL': '/var/mail/cltbld', 23:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:06 INFO - 'MOZ_NO_REMOTE': '1', 23:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:06 INFO - 'NO_EM_RESTART': '1', 23:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:06 INFO - 'PWD': '/builds/slave/test', 23:09:06 INFO - 'SHELL': '/bin/bash', 23:09:06 INFO - 'SHLVL': '1', 23:09:06 INFO - 'TERM': 'linux', 23:09:06 INFO - 'TMOUT': '86400', 23:09:06 INFO - 'USER': 'cltbld', 23:09:06 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:06 INFO - '_': '/tools/buildbot/bin/python'} 23:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:07 INFO - Downloading/unpacking flake8 23:09:07 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:09:07 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:09:07 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:09:07 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:09:07 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:09:07 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:09:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:09:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:09:08 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:09:08 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:09:08 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:09:08 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:09:08 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:09:08 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:09:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:09:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:09:09 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:09:09 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:09:09 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:09:09 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:09:09 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:09:09 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:09:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:09:09 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:09:09 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:09:09 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:09:09 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:09:09 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:09:09 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:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:09:09 INFO - no previously-included directories found matching 'docs/_build' 23:09:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:09:09 INFO - Running setup.py install for pep8 23:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:09:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:09:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:09:09 INFO - no previously-included directories found matching 'docs/_build' 23:09:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:09:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:09:09 INFO - Cleaning up... 23:09:09 INFO - Return code: 0 23:09:09 INFO - Running post-action listener: _resource_record_post_action 23:09:09 INFO - Running post-action listener: _start_resource_monitoring 23:09:09 INFO - Starting resource monitoring. 23:09:09 INFO - ##### 23:09:09 INFO - ##### Finished create-virtualenv step (success) 23:09:09 INFO - ##### 23:09:09 INFO - ##### 23:09:09 INFO - ##### Running install step. 23:09:09 INFO - ##### 23:09:09 INFO - Running pre-action listener: _resource_record_pre_action 23:09:09 INFO - Running main action method: install 23:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:10 INFO - Reading from file tmpfile_stdout 23:09:10 INFO - Detecting whether we're running mozinstall >=1.0... 23:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:09:10 INFO - Reading from file tmpfile_stdout 23:09:10 INFO - Output received: 23:09:10 INFO - Usage: mozinstall [options] installer 23:09:10 INFO - Options: 23:09:10 INFO - -h, --help show this help message and exit 23:09:10 INFO - -d DEST, --destination=DEST 23:09:10 INFO - Directory to install application into. [default: 23:09:10 INFO - "/builds/slave/test"] 23:09:10 INFO - --app=APP Application being installed. [default: firefox] 23:09:10 INFO - mkdir: /builds/slave/test/build/application 23:09:10 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:09:10 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:09:26 INFO - Reading from file tmpfile_stdout 23:09:26 INFO - Output received: 23:09:26 INFO - /builds/slave/test/build/application/firefox/firefox 23:09:26 INFO - Running post-action listener: _resource_record_post_action 23:09:26 INFO - ##### 23:09:26 INFO - ##### Finished install step (success) 23:09:26 INFO - ##### 23:09:26 INFO - ##### 23:09:26 INFO - ##### Running run-tests step. 23:09:26 INFO - ##### 23:09:26 INFO - Running pre-action listener: _resource_record_pre_action 23:09:26 INFO - Running main action method: run_tests 23:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:09:26 INFO - Python 2.7.3 23:09:26 INFO - Return code: 0 23:09:26 INFO - grabbing minidump binary from tooltool 23:09:26 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:09:26 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 0x2847010>, '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 0x2847ef0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28483a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:09:26 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:09:26 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:09:26 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:09:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:09:27 INFO - Return code: 0 23:09:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:09:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:09:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:09:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:09:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:09:28 INFO - Return code: 0 23:09:28 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-006', '--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:09:28 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-006 --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:09:28 INFO - Using env: {'DISPLAY': ':0', 23:09:28 INFO - 'HOME': '/home/cltbld', 23:09:28 INFO - 'LANG': 'en_US.UTF-8', 23:09:28 INFO - 'LANGUAGE': 'en_US:en', 23:09:28 INFO - 'LOGNAME': 'cltbld', 23:09:28 INFO - 'MAIL': '/var/mail/cltbld', 23:09:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:09:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:28 INFO - 'MOZ_NO_REMOTE': '1', 23:09:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:28 INFO - 'NO_EM_RESTART': '1', 23:09:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:28 INFO - 'PWD': '/builds/slave/test', 23:09:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:09:28 INFO - 'SHELL': '/bin/bash', 23:09:28 INFO - 'SHLVL': '1', 23:09:28 INFO - 'TERM': 'linux', 23:09:28 INFO - 'TMOUT': '86400', 23:09:28 INFO - 'USER': 'cltbld', 23:09:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957', 23:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:28 INFO - '_': '/tools/buildbot/bin/python'} 23:09:28 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-006', '--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:09:28 INFO - mozversion application_buildid: 20160606203027 23:09:28 INFO - mozversion application_changeset: 580436387d62e0a40fcc781832c3291858121816 23:09:28 INFO - mozversion application_display_name: Nightly 23:09:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:09:28 INFO - mozversion application_name: Firefox 23:09:28 INFO - mozversion application_remotingname: firefox 23:09:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:09:28 INFO - mozversion application_vendor: Mozilla 23:09:28 INFO - mozversion application_version: 50.0a1 23:09:28 INFO - mozversion platform_buildid: 20160606203027 23:09:28 INFO - mozversion platform_changeset: 580436387d62e0a40fcc781832c3291858121816 23:09:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:09:28 INFO - mozversion platform_version: 50.0a1 23:09:28 INFO - using testdate: 1465279768 23:09:28 INFO - actual date: 1465279768 23:09:28 INFO - starting webserver on 'localhost:42247' 23:09:28 INFO - SUITE-START | Running 2 tests 23:09:28 INFO - TEST-START | dromaeo_css 23:09:28 INFO - operating with platform_type : linux_ 23:09:28 INFO - Initialising browser for dromaeo_css test... 23:09:28 INFO - TEST-INFO | started process 5146 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprgirK7/profile http://localhost:42247/getInfo.html) 23:09:32 INFO - PROCESS | 5146 | __metrics Screen width/height:1600/1200 23:09:32 INFO - PROCESS | 5146 | colorDepth:24 23:09:32 INFO - PROCESS | 5146 | Browser inner width/height: 1024/697 23:09:32 INFO - PROCESS | 5146 | __metrics 23:09:33 ERROR - PROCESS | 5146 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:09:33 INFO - PROCESS | 5146 | console.error: PushService: 23:09:33 INFO - PROCESS | 5146 | stateChangeProcessEnqueue: Error transitioning state 23:09:33 INFO - PROCESS | 5146 | UnknownError 23:09:34 INFO - TEST-INFO | 5146: exit 0 23:09:34 INFO - Browser initialized. 23:09:34 INFO - Running cycle 1/1 for dromaeo_css test... 23:09:34 INFO - TEST-INFO | started process 5226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprgirK7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:46 INFO - PROCESS | 5226 | RSS: Main: 156454912 23:09:46 INFO - PROCESS | 5226 | 23:10:37 INFO - PROCESS | 5226 | 1465279837250 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:12:45 INFO - PROCESS | 5226 | Cycle 1(1): loaded http://localhost:42247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42247/tests/dromaeo/cssquery-ext.html) 23:12:46 INFO - PROCESS | 5226 | RSS: Main: 253501440 23:12:46 INFO - PROCESS | 5226 | 23:15:48 INFO - PROCESS | 5226 | Cycle 1(1): loaded http://localhost:42247/tests/dromaeo/cssquery-ext.html (next: http://localhost:42247/tests/dromaeo/cssquery-jquery.html) 23:15:49 INFO - PROCESS | 5226 | RSS: Main: 240603136 23:15:49 INFO - PROCESS | 5226 | 23:18:51 INFO - PROCESS | 5226 | Cycle 1(1): loaded http://localhost:42247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42247/tests/dromaeo/cssquery-mootools.html) 23:18:51 INFO - PROCESS | 5226 | RSS: Main: 238895104 23:18:51 INFO - PROCESS | 5226 | 23:21:53 INFO - PROCESS | 5226 | Cycle 1(1): loaded http://localhost:42247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42247/tests/dromaeo/cssquery-prototype.html) 23:21:54 INFO - PROCESS | 5226 | RSS: Main: 236900352 23:21:54 INFO - PROCESS | 5226 | 23:24:56 INFO - PROCESS | 5226 | Cycle 1(1): loaded http://localhost:42247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42247/tests/dromaeo/cssquery-yui.html) 23:24:57 INFO - PROCESS | 5226 | RSS: Main: 219467776 23:24:57 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | Cycle 1(1): loaded http://localhost:42247/tests/dromaeo/cssquery-yui.html (next: http://localhost:42247/tests/dromaeo/cssquery-dojo.html) 23:28:00 INFO - PROCESS | 5226 | RSS: Main: 290324480 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | __start_tp_report 23:28:00 INFO - PROCESS | 5226 | _x_x_mozilla_page_load 23:28:00 INFO - PROCESS | 5226 | _x_x_mozilla_page_load_details 23:28:00 INFO - PROCESS | 5226 | |i|pagename|runs| 23:28:00 INFO - PROCESS | 5226 | |0;dojo.html;3088.7837851350414;3096.086654436941;3109.8911538096163;3128.890488832891;3138.795978261413;5143.074246635604;5153.30430391897;5277.208418737191;5410.756515956781;5424.10502267126;5298.337707786527;5306.787728490865;5317.335333083364;5340.49265319795;5384.219288203209;4766.189747742875;4813.253945638432;4855.417349918015;4865.318855360252;5035.697858128506;4932.7780249888665;4962.478939711333;4966.627502937276;5005.174146265862;5019.322391477141;4704.811807527695;4753.47711751707;4765.7140571565815;4842.225243960949;4858.927116093261;4831.541003604652;4856.562909338141;4873.122837889178;4914.140025495524;4964.553190212862;4869.732164730938;4892.192788189945;4899.436564795059;4902.338184345103;4903.239997800347;5091.8981620367385;5212.4266330703595;5299.205119232108;5337;5367.302250707383;5763.164341170507;5787.826365209051;5792.044312688401;5810.854728631776;5815.563832712563;5507.311586051744;5793.391693872125;5826.708664566755;5829;5840;5253.0544501989625;5318.627696061278;5319.813806516753;5401.756920938567;5402.216678581623;5907.497862681635;5927.229460170237;5930.495907847795;5933.169356290123;5934.258217722785;5417.512423881791;5555;5615.157726341082;5642.915356269659;5648.63283886546;5664.801731939362;5727.140928860705;5777.277840269966;5777.508911742465;5783.0457974434585;5689.630174038674;5698.572607054488;5700.486956173964;5700.800471983461;5703.287089113861;5498.395176530578;5623.887522249532;5667.744951477193;5685.403032681592;5696.031674615326;4327.459067616548;4342.6742994275555;4346.630536404441;4382.408374869352;4384.627306678968;3874.438206460104;3888.0862997195636;3891.377379619304;3924.9803750981064;3926.2540117377607;5099.56653684441;5196.220566914917;5279.366476022825;5345.438728933406;5350.9732451337495;5728.341240757283;5739.62692424991;5748.511376533042;5787.565932555075;5805.01314776489;5346.705931173823;5346.786128554814;5353.678779273256;5354.678719276856;5356.437574054668;4629.583337499641;4748.596369308579;4776.259679749644;4782.995570930074;4832.8791780205775;5893.174955506147;5903.26209223847;5906.291245050621;5906.7637294508595;5919.378465261172;5752.367239603724;5761.827145185651;5771.076627739541;5775.942240577709;5776.018076927016;5648.519875810603;5868.383819699041;5915.260592425947;5928.614640048383;5949.434803693643;5616.971915140398;5633.0705433604635;5635.943640563706;5643.746031428677;5654.943450565606;5499.560035197094;5686.232358631697;5732.34078081026;5748.885022299614;5749.683767392667;5624.634398764068;5640.971795140998;5649.209110724584;5672.688002159921;5700.173474846207;5596.328440586993;5605.523530499953;5608.635438696471;5612.298462692163;5616.803411880564;5250;5356.5982551308025;5414.972925135349;5481.096417694218;5485.670859748428;5519.254900588369;5675.77296908119;5684;5690.886182276414;5711.942880571307;5529.087700529368;5530.115181570929;5530.446955304437;5530.5022547970875;5574.219609254626 23:28:00 INFO - PROCESS | 5226 | |1;ext.html;581.1021971054722;596.2665920917269;617.120603140525;621.8414304352391;639.3095456906541;31422.84288578557;34606;34632.653673463225;34638.82680586597;42300;40872;45153.54846451535;45370.546294537045;45399.77300113495;45413.31880021794;8832.381733278673;9055;9061.50161741104;9064.501452420114;9066.773330666752;8746.781330466725;8907.643694252254;8911.465312081264;8914.33142514314;8918.55407229636;8918.063603321654;8920.46477211366;8923.643054277822;8952.910470895273;8954.731358059269;8892.333075019367;8928.464292142493;8946.329025323126;8948.105189481064;8953.641854325884;8802.691905783331;8869.822603547893;8875.556222188929;8895.866562001574;8895.955520222422;41715.791421043;42214.57785422168;42247.366289505684;42272.78863605693;42310;3482.82585870708;3943.7042221833476;4018.548680324149;4127.814248358831;4270.316749320094;32185.51721724176;32373.352532949444;32919.50620740691;32921.83539082289;32928.83535582331;4286.057067445157;4313.568643135693;4319.136172765459;4364.6290065344165;4558.452985641711;350579;352062;352679;353123;353973;57199;57392;57721.4227857716;57750.71124644434;57846.4215357841;35182.64817351845;35891.641083589355;35952.28095438077;37974;38030;16582.17089145538;16815.747763783555;16839;16881.493555193363;16900.492985210465;12672.80990785139;12749.745005099847;12751.107422480338;12755;12759.043071769654;19365.8063419364;19522.731022483495;19558;19665.230129288433;19771.901140494207;7038.683259253346;7174.1032370953635;7234.095738032746;7286.214877355082;7408.85182296351;987.2792861211356;1062.351965301181;1103.8564986551696;1111.7630095166112;1120.2214460949563;33305.16737081592;33390.833045835105;33397;33580.49629255563;33869.49195762065;1136.6192325570862;1146.7821113988316;1184.7097461121907;1215.1129675337054;1223.9694007649703;16947;17324.740128898076;17438.65122697539;17500.21249043796;17562.473125806246;566246;571992;572528;573191;576456;58465.415345846;58931;58982.70508647429;58999;59126.704366478756;1250.5623031938749;1290.8450985881566;1344.9327533623475;1369.233229391544;1370.7052983608571;30718.84640576783;34545.48181777326;34966.47550286697;34976;35284;51340;51711;52005;52024;52040.47959520508;330350;330521;331330;332414;332647;27832.443351133268;27849.44301114007;28330.433391331644;29017.12948611545;29089.563656545593;49732.25401619051;50039;50235.74882125566;51236.23145652893;51284.48715512797;40321.395179072926;41286.79356603198;41853.58146418497;42205.36691949562;42211.577884220766;40994;41038.38442423426;41512.792436038835;42465.15069698651;42509.57490425056;34672.82663586666;35640.108997275274;42110.57889421189;42335.36496952487;43084.569154308054;36438.453423198145;38191.80904095462;38259;38768.224635506565;38819.41770873496;28319;28919;28962.565561517014;29135.417291654474;29175.416491670472 23:28:00 INFO - PROCESS | 5226 | |2;jquery.html;385.926673931953;389.4878235120816;389.5153023868797;392.57601790066724;411.4918076175922;14376.424943002294;16131.596710082224;17793.822061779378;19286.035698215084;19318.227270909163;14907.478238261663;17422.738658920127;18304.542386440346;18427.631447371044;18456.169472373742;5544.22380866679;5603.131514615241;5848.795292164775;5857.824265272027;5900.203472531196;3837.616238376168;5405.729713514328;5765.4522820332;5802.158686990384;5823.417658234164;5679;5740.56945729072;5784.884302313952;5793.333766616851;5798.043322851724;5759.4816466518005;5767.480926716595;5933.27934898881;6320.304766475684;6340.936590634081;5607.691576963306;5628.26832511775;5640.605157638967;5647.237622920935;5669.9433005669825;12839.422225999853;13424;13472.595822125351;13502.527411540548;13504.054716169872;2191.3316438486254;2197.198022721695;2277.851939623919;2438.6463962725306;2717.6467059282363;9056.366054376196;9209.769755756093;9488.430694158373;9538.809223815555;9661.372010819345;1845.1973391574709;1871.9532011699816;1875.6623807714607;1938.2150229157214;1975.8715683480527;78359.90660607099;82054.17945820525;82630;82922;84027.15972840255;10566.260361774603;10571.10145637614;10878.075363594025;10949.343039417661;11027.503762330714;14978.17620030887;15099.622509437133;15150.54548363551;15158.242087895782;15160.393584256726;7012.193597736224;7358.816029599303;7686.192949740197;7784.688612455551;7913.44605877583;7332.26996311441;7374.151972523121;7548.811279718051;7685.884711729217;7906.446548741648;4626.1441633297945;4640.025594625099;4741.1465936131135;4797.328374027569;4815.061063092733;1781.5189898727272;1852.4998250472568;1901.4390754727388;1967.7441932548677;2019.8687085339407;905.2848249882519;924.4360939826701;1017.59296281488;1069.5187165775433;1130.2653275371074;18305.359312424;18643.90678046628;18646.627067458845;18928.621427571372;18965.336213232746;972.1250874213265;988.2341185581089;1003.6286573967678;1009.4700282351824;1026.6047571684805;10242.948785256027;10405.167586593054;10516.526756295985;10553.63062292816;10554.788904221872;494645;499976;508406;514181;514389;92953.53523232341;93639;94133.52933235427;95018;96284.51857740666;985.4186030242191;990.1435258501344;993.6103670672045;995.2237254941012;996.9451680157661;16356.182190890646;16428.835711642732;16507.257173427464;16635.750463743294;17312.826871731122;42027.57972420237;43173.352399714655;43244.56755432405;45334.77332613448;45377;57757.42242577521;58347;58522;59613;59629.7018514922;8230.670773169006;8538.91461085406;8570.142985701379;8718.43330183536;8757.562121893752;11754.529818807152;12692.238465692088;12791.744165116459;13346.733065338445;13352.132111412728;11403;11510.194286399872;11564.653060408202;11579.594714184963;11788.351640659846;11131.45879593829;11230;11351.432428378714;11502.827457587975;11644.6506604802;11492.195546311677;11888.405579721153;11965.581204657796;12030.75938481208;12096.274223546616;8500.787480313042;8513.404061715868;8515.148485151436;8529.445586036887;8714.215720585178;10823.242373033812;11031;11542.59600913964;11629.59296424621;12171.452284647035 23:28:00 INFO - PROCESS | 5226 | |3;mootools.html;1359.4154513559165;1463.1075044223026;1506.4953240664377;1645.3089702325021;1807.557148498618;7893.723719669812;8296.543690097042;8309.293710034646;8474.74575762728;8492.065872753992;8250.422470427058;8459.196376344238;8460.788480287996;8462.87305690415;8467.491950482976;1897.0609548273555;1952.5606738483868;2206.580749657568;2286.0284379138843;2315.0276883708884;2309.191782875997;2358.84667496613;2371.8576885386847;2372.9406764830846;2374.442006128558;2266.8186545076355;2286.6684330772027;2290.0954123121355;2291.106468477295;2317.44381348476;1909.6180763847217;2126.8617539860015;2198.0108950972126;2220.500387412835;2258.7515373308925;2249.5275992041693;2272.091163534599;2272.125231785758;2272.977270227293;2275.6245219538755;8155.877661835078;8177.386695997824;8193.34453243745;8200.302974247195;8205;4845.345878306419;4967.850964471072;4968;4970.577500912426;4972.925406118911;3078.722914937644;3131.312632426362;3191.521271809224;3217.8391080446117;3231.224506118538;1007.5163921317809;1022.3405903192473;1033.452270296744;1056.4717641179411;1072.544168728295;11501.539938402453;11623.302601843916;11637;11678.299302041905;11768.823467647991;4421.049474363027;4452.376667266585;4572.7942242599165;4575.519570445122;4680.836170734008;9316.161545460805;9359.438433694;9432.245420366355;9433.198178154797;9441.291903107294;4973.229149481836;4980.551750342486;4985.875353116201;4987.551120399109;4993.775280112331;3889;3943.093088589583;4450.087732014929;4571.497135315112;4679.415073115861;3606.891793246207;3718.1076541630355;3720.4419337099653;3775.6413140751583;3806.9048273792823;1116.7598966222301;1129.5481807277156;1135.0635725526474;1139.5840518210794;1141.2866958151155;999.5406706208871;1007.561710655867;1009.1724785675822;1014.1886490807325;1019.1999280564721;5893.528517718572;5918.082697181943;6018.21763170785;6048.243969503812;6110.480609148183;460.34400978605356;472.6951116529819;478.6410192355733;481.04992639537335;482.3319702212391;3964.60353964607;4038.394240863835;4059.2084543513733;4069.145479449349;4141.710080294411;9617.086376794194;10159.898401016044;10182.745431364126;10192.388456692623;10194.3373680712;9924.057214564606;10006.899931000597;10051.748706282255;10075.848862267072;10094.848577271347;7764.417668674759;7819.609019549;7839.25527074927;7844.9607751960875;7856.528608283521;5757.078867381367;5771.076627739541;5842.766289348378;5870.236869206976;5909;9420.246380289424;9458.668946586837;9578.568964396758;9586.808263834824;9593.952030240083;9179.219766319937;9504.619815207314;9629.614815407585;9693.70918872435;9694.418334899929;6807.421369183675;7169.175544812413;7345.338919497271;7417.295356941082;7649.847003059796;8217.630206640595;8226.506409615442;8237.176282371714;8288.129746376544;8309.542975136426;7130.500864939612;7315.670794814139;7482.3639990601405;7579.886301705368;7604.163542010264;7103.218645949045;7339.559626422431;7370.373518251009;7506.099268087864;7510.173880873209;6498.902516462352;6515.6742162892615;6541.738330466728;6578.703958321983;6582.341765823571;4984.551390374883;5017.448080711192;5085.5931525477135;5199;5218.452062533531;5201.7399130044105;5366.731663416892;5385.380681221553;5419.467585467246;5496.862578435571 23:28:00 INFO - PROCESS | 5226 | |4;prototype.html;326.5232760170151;333.8063922924704;339.21641009268586;340.3057762165183;341.1488336600183;5243.898781255936;5261.105612045952;5285.2600635911;5295.655782374143;5297.973510132453;5136.743162841862;5142.588592912567;5153.3558305211845;5172.586193104552;5252.606054545905;3876.8449262029485;3911.9413208801748;4011.438398624195;4039.5758445363253;4076.51081870176;4013.5785742497055;4049.129437171008;4050.10897602527;4062.476571285768;4064.817083231247;3845.0582455859753;3981.741186822862;3984.482017337742;3999.860004899829;4004.3993400989793;3756.643118903727;3936.2127574485216;3939.9409008864745;3989.5264935739738;4064.329385651364;3579.1052236940764;3590.7127429805555;3608.9097772555633;3619.7647152934974;3622.3117607654726;4970.13022721026;5013.423456302536;5015.749212539358;5055.3317405256175;5067.442581316052;4502.324651302298;4636.142313671948;4640.303954406866;4656.696125084982;4662.8134874604975;5347.893042139174;5394.867077913643;5399.163129714901;5401.405845357008;5469.808556700496;3758.342290099222;3919.9041246379593;3940.54683711374;3953.3476976298875;3974.5627980922077;5513.696746678931;5616.241807355996;5641.407652196543;5643.294588176519;5657.23627310312;3947.388154836005;3964.643182113566;4003.6997225208224;4004.799760011988;4037.4549435826375;3932.508436445444;4075.816588253536;4088.836446542105;4102.118044620421;4104.938425923614;4597.735622703716;4598.2412901871485;4609.239475486507;4628.074385122989;4633.72197668141;5470.288862447856;5486.753096110685;5492.862678432991;5493.560515158778;5496.862578435491;1363.8022486739367;1401.8387885393106;1412.971740565183;1416.8016477693131;1419.6237996930822;864.1229152410309;886.3529623374843;887.6138879587401;888.8311220868014;902.8149229407956;5126.974365128225;5208.947910520922;5215.478452154754;5224.294720212799;5231.424543300234;5612.270404847343;5639.956608027528;5652.463016013472;5653.491185793298;5661.7735290588735;5049.520295571915;5199.16765755055;5273.551748101454;5274.12976858814;5287.841364759063;2942.411517696469;2947.882084716587;2965.258685328668;2984.9701502984694;2993.730564249228;5791.739371728282;5811.27359080115;5811.854703632358;5852.590318677737;5858.384869588717;5584.52531534816;5657.632253903483;5689.943100569024;5699.800506982236;5713.771449141987;5737.713114344266;5810.912836307374;5821.476067153976;5831.271091113526;5848.824535263949;5375.973120134374;5568.721563921868;5628.437156284339;5647.915281270702;5649.887002259849;5513.889722205614;5544;5546.55627549787;5551.666899986014;5571.415001424954;5776.971115144397;5783.219265399117;5793.971030144991;5811.476967072984;5814.215080964016;3868.1876805871084;3921.1569512553797;3925.743761996134;3968.722189446711;3969.563348031772;5552.056150454352;5559.19391688211;5588.161775733673;5592.713675854658;5635.802746903839;5478.589105817;5522.282103326703;5561.249231353715;5577.637453565505;5583;5207.479252074762;5395.381385584387;5416.431274716098;5453.291072160594;5470.890582188414;5202.635815492879;5264.210368444611;5267.420583735862;5320.866978325573;5361.195820626937;5370.301860758076;5473.781048758005;5484.698341591251;5500.4224556420995;5502.312210973629;5235.110031294613;5334.146536554133;5393.946060539502;5421;5422.48486393792 23:28:00 INFO - PROCESS | 5226 | |5;yui.html;673.3303495947087;740.2116745665869;748.7985323149938;789.2975252025697;818.4598165210961;14322.212278324689;15575.221238938051;15835.208239588032;15842.60393490162;15869.206539673;14497.5650730478;15452.227388630552;16027.599310017255;16155.353785848582;16965.83034169658;677.1535580524345;828.8093738440148;830.489249111795;835.5989125219899;836.0427310729211;790.3163763344694;833.7415401225609;835.9540225287637;837.5560952695051;837.7863644770575;870.1124852650292;871.0505548951674;873.681106396164;874.9431286966359;874.995625021874;698.50755217572;723.0058669329671;868.4355169140047;868.8132051608903;869.6347534035689;539.6761942834307;604.0546544657641;623.6008954269271;624.272722278545;624.3600309682566;15571;15613.68772624541;15619.609509762347;15623.843761562353;15699.686006279924;13761.862381376159;14061.296935153303;14101.506447274296;14186.43254269828;14246.572602821932;13335.199888006653;13396.129251598712;13423.798643020362;13529.864701352959;13585.796213056812;4429.756363400012;4454.532274111204;4511.368408422823;4593.655475839292;4707.976460117678;71954.28045719529;72297;72504.6374768128;73681.63159184223;73682.63158684225;10815;10993.780124397466;11004.39475828821;11147.888521114848;11508.884911150948;20491;20544.280950166973;20583.8970805145;20601.587968240852;20616.07227674758;8201.548914809624;8252.381071419548;8270.338372930271;8272.33821294295;8683;4501.459824820977;4561.589456948891;4599.195140850405;4624.560666736655;4780.784864681028;3414.3342048300838;3510.596281427617;3518.648135186461;3520.1727594015388;3838.865639702653;662.4998126414622;676.6109487044969;678.2945736434152;681.7886455198905;686.7115811359241;636.4908073541241;685.4071228387409;762.1273641680356;762.4510352546158;770.6300975531776;10159.949200253952;10442.68671939843;10518.211134164969;10581.788364232827;10692.251542391958;627.8650090230623;646.8059582125437;702.9964850175717;759.9544027358377;765.64780201107;4731.4558825735485;4871.585915197248;4957.702537847741;4977.32806071183;4994.950050499521;115427;119493;119921;120283;124315.37842310731;101636.49181754045;102118.4894075525;102610.48694756479;103540.48229758954;105556.47221763842;86575.56712216524;87628.56185719158;87705.56147219223;87754;88102;9489.857652135086;9604.327697061139;9748.317617766972;9748.561314740995;9802.65690700822;53265;53366.46633533615;53710;53825.999999998436;55309.723451384096;73407;74854.25145748473;78468;81150;81539.18460815553;12432.378381081091;12500.312482813533;12707.110502264753;12710.173838700455;12887.93556032214;13615.523456679364;13974.161550307013;14244.501442449464;14259.928700356846;14625.195614240893;13248;13787;13956.162630242217;13971.93014034964;14054.297285135906;12994.675133121747;13122.14706044104;13212.141210821266;13379.933100334436;13612.701457790208;12998.446142085371;13477.663058423617;13496.190228586316;13623.727525449634;13630.04589678713;10075.697729068139;10783.568657253936;10884.40135792539;10947.288426252268;10949.890501094887;10650.467476625983;11179.987602231677;11223;11292.661220163409;11305.830412543653 23:28:00 INFO - PROCESS | 5226 | __end_tp_report 23:28:00 INFO - PROCESS | 5226 | __start_cc_report 23:28:00 INFO - PROCESS | 5226 | _x_x_mozilla_cycle_collect,908 23:28:00 INFO - PROCESS | 5226 | __end_cc_report 23:28:00 INFO - PROCESS | 5226 | __startTimestamp1465280880819__endTimestamp 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | ------- Summary: start ------- 23:28:00 INFO - PROCESS | 5226 | Number of tests: 6 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | [#0] dojo.html Cycles:165 Average:5301.91 Median:5499.56 stddev:608.39 (11.1%) stddev-sans-first:584.96 23:28:00 INFO - PROCESS | 5226 | Values: 3088.8 3096.1 3109.9 3128.9 3138.8 5143.1 5153.3 5277.2 5410.8 5424.1 5298.3 5306.8 5317.3 5340.5 5384.2 4766.2 4813.3 4855.4 4865.3 5035.7 4932.8 4962.5 4966.6 5005.2 5019.3 4704.8 4753.5 4765.7 4842.2 4858.9 4831.5 4856.6 4873.1 4914.1 4964.6 4869.7 4892.2 4899.4 4902.3 4903.2 5091.9 5212.4 5299.2 5337.0 5367.3 5763.2 5787.8 5792.0 5810.9 5815.6 5507.3 5793.4 5826.7 5829.0 5840.0 5253.1 5318.6 5319.8 5401.8 5402.2 5907.5 5927.2 5930.5 5933.2 5934.3 5417.5 5555.0 5615.2 5642.9 5648.6 5664.8 5727.1 5777.3 5777.5 5783.0 5689.6 5698.6 5700.5 5700.8 5703.3 5498.4 5623.9 5667.7 5685.4 5696.0 4327.5 4342.7 4346.6 4382.4 4384.6 3874.4 3888.1 3891.4 3925.0 3926.3 5099.6 5196.2 5279.4 5345.4 5351.0 5728.3 5739.6 5748.5 5787.6 5805.0 5346.7 5346.8 5353.7 5354.7 5356.4 4629.6 4748.6 4776.3 4783.0 4832.9 5893.2 5903.3 5906.3 5906.8 5919.4 5752.4 5761.8 5771.1 5775.9 5776.0 5648.5 5868.4 5915.3 5928.6 5949.4 5617.0 5633.1 5635.9 5643.7 5654.9 5499.6 5686.2 5732.3 5748.9 5749.7 5624.6 5641.0 5649.2 5672.7 5700.2 5596.3 5605.5 5608.6 5612.3 5616.8 5250.0 5356.6 5415.0 5481.1 5485.7 5519.3 5675.8 5684.0 5690.9 5711.9 5529.1 5530.1 5530.4 5530.5 5574.2 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | [#1] ext.html Cycles:180 Average:57859.94 Median:41016.19 stddev:114870.40 (280.1%) stddev-sans-first:115112.14 23:28:00 INFO - PROCESS | 5226 | Values: 581.1 596.3 617.1 621.8 639.3 31422.8 34606.0 34632.7 34638.8 42300.0 40872.0 45153.5 45370.5 45399.8 45413.3 8832.4 9055.0 9061.5 9064.5 9066.8 8746.8 8907.6 8911.5 8914.3 8918.6 8918.1 8920.5 8923.6 8952.9 8954.7 8892.3 8928.5 8946.3 8948.1 8953.6 8802.7 8869.8 8875.6 8895.9 8896.0 41715.8 42214.6 42247.4 42272.8 42310.0 3482.8 3943.7 4018.5 4127.8 4270.3 32185.5 32373.4 32919.5 32921.8 32928.8 4286.1 4313.6 4319.1 4364.6 4558.5 350579.0 352062.0 352679.0 353123.0 353973.0 57199.0 57392.0 57721.4 57750.7 57846.4 35182.6 35891.6 35952.3 37974.0 38030.0 16582.2 16815.7 16839.0 16881.5 16900.5 12672.8 12749.7 12751.1 12755.0 12759.0 19365.8 19522.7 19558.0 19665.2 19771.9 7038.7 7174.1 7234.1 7286.2 7408.9 987.3 1062.4 1103.9 1111.8 1120.2 33305.2 33390.8 33397.0 33580.5 33869.5 1136.6 1146.8 1184.7 1215.1 1224.0 16947.0 17324.7 17438.7 17500.2 17562.5 566246.0 571992.0 572528.0 573191.0 576456.0 58465.4 58931.0 58982.7 58999.0 59126.7 1250.6 1290.8 1344.9 1369.2 1370.7 30718.8 34545.5 34966.5 34976.0 35284.0 51340.0 51711.0 52005.0 52024.0 52040.5 330350.0 330521.0 331330.0 332414.0 332647.0 27832.4 27849.4 28330.4 29017.1 29089.6 49732.3 50039.0 50235.7 51236.2 51284.5 40321.4 41286.8 41853.6 42205.4 42211.6 40994.0 41038.4 41512.8 42465.2 42509.6 34672.8 35640.1 42110.6 42335.4 43084.6 36438.5 38191.8 38259.0 38768.2 38819.4 28319.0 28919.0 28962.6 29135.4 29175.4 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | [#2] jquery.html Cycles:180 Average:29132.03 Median:402.03 stddev:83567.75 (20786.2%) stddev-sans-first:83774.31 23:28:00 INFO - PROCESS | 5226 | Values: 385.9 389.5 389.5 392.6 411.5 14376.4 16131.6 17793.8 19286.0 19318.2 14907.5 17422.7 18304.5 18427.6 18456.2 5544.2 5603.1 5848.8 5857.8 5900.2 3837.6 5405.7 5765.5 5802.2 5823.4 5679.0 5740.6 5784.9 5793.3 5798.0 5759.5 5767.5 5933.3 6320.3 6340.9 5607.7 5628.3 5640.6 5647.2 5669.9 12839.4 13424.0 13472.6 13502.5 13504.1 2191.3 2197.2 2277.9 2438.6 2717.6 9056.4 9209.8 9488.4 9538.8 9661.4 1845.2 1872.0 1875.7 1938.2 1975.9 78359.9 82054.2 82630.0 82922.0 84027.2 10566.3 10571.1 10878.1 10949.3 11027.5 14978.2 15099.6 15150.5 15158.2 15160.4 7012.2 7358.8 7686.2 7784.7 7913.4 7332.3 7374.2 7548.8 7685.9 7906.4 4626.1 4640.0 4741.1 4797.3 4815.1 1781.5 1852.5 1901.4 1967.7 2019.9 905.3 924.4 1017.6 1069.5 1130.3 18305.4 18643.9 18646.6 18928.6 18965.3 972.1 988.2 1003.6 1009.5 1026.6 10242.9 10405.2 10516.5 10553.6 10554.8 494645.0 499976.0 508406.0 514181.0 514389.0 92953.5 93639.0 94133.5 95018.0 96284.5 985.4 990.1 993.6 995.2 996.9 16356.2 16428.8 16507.3 16635.8 17312.8 42027.6 43173.4 43244.6 45334.8 45377.0 57757.4 58347.0 58522.0 59613.0 59629.7 8230.7 8538.9 8570.1 8718.4 8757.6 11754.5 12692.2 12791.7 13346.7 13352.1 11403.0 11510.2 11564.7 11579.6 11788.4 11131.5 11230.0 11351.4 11502.8 11644.7 11492.2 11888.4 11965.6 12030.8 12096.3 8500.8 8513.4 8515.1 8529.4 8714.2 10823.2 11031.0 11542.6 11629.6 12171.5 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | [#3] mootools.html Cycles:180 Average:5506.35 Median:4971.75 stddev:3093.76 (62.2%) stddev-sans-first:3086.74 23:28:00 INFO - PROCESS | 5226 | Values: 1359.4 1463.1 1506.5 1645.3 1807.6 7893.7 8296.5 8309.3 8474.7 8492.1 8250.4 8459.2 8460.8 8462.9 8467.5 1897.1 1952.6 2206.6 2286.0 2315.0 2309.2 2358.8 2371.9 2372.9 2374.4 2266.8 2286.7 2290.1 2291.1 2317.4 1909.6 2126.9 2198.0 2220.5 2258.8 2249.5 2272.1 2272.1 2273.0 2275.6 8155.9 8177.4 8193.3 8200.3 8205.0 4845.3 4967.9 4968.0 4970.6 4972.9 3078.7 3131.3 3191.5 3217.8 3231.2 1007.5 1022.3 1033.5 1056.5 1072.5 11501.5 11623.3 11637.0 11678.3 11768.8 4421.0 4452.4 4572.8 4575.5 4680.8 9316.2 9359.4 9432.2 9433.2 9441.3 4973.2 4980.6 4985.9 4987.6 4993.8 3889.0 3943.1 4450.1 4571.5 4679.4 3606.9 3718.1 3720.4 3775.6 3806.9 1116.8 1129.5 1135.1 1139.6 1141.3 999.5 1007.6 1009.2 1014.2 1019.2 5893.5 5918.1 6018.2 6048.2 6110.5 460.3 472.7 478.6 481.0 482.3 3964.6 4038.4 4059.2 4069.1 4141.7 9617.1 10159.9 10182.7 10192.4 10194.3 9924.1 10006.9 10051.7 10075.8 10094.8 7764.4 7819.6 7839.3 7845.0 7856.5 5757.1 5771.1 5842.8 5870.2 5909.0 9420.2 9458.7 9578.6 9586.8 9594.0 9179.2 9504.6 9629.6 9693.7 9694.4 6807.4 7169.2 7345.3 7417.3 7649.8 8217.6 8226.5 8237.2 8288.1 8309.5 7130.5 7315.7 7482.4 7579.9 7604.2 7103.2 7339.6 7370.4 7506.1 7510.2 6498.9 6515.7 6541.7 6578.7 6582.3 4984.6 5017.4 5085.6 5199.0 5218.5 5201.7 5366.7 5385.4 5419.5 5496.9 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | [#4] prototype.html Cycles:180 Average:4587.31 Median:5256.86 stddev:1357.57 (25.8%) stddev-sans-first:1323.18 23:28:00 INFO - PROCESS | 5226 | Values: 326.5 333.8 339.2 340.3 341.1 5243.9 5261.1 5285.3 5295.7 5298.0 5136.7 5142.6 5153.4 5172.6 5252.6 3876.8 3911.9 4011.4 4039.6 4076.5 4013.6 4049.1 4050.1 4062.5 4064.8 3845.1 3981.7 3984.5 3999.9 4004.4 3756.6 3936.2 3939.9 3989.5 4064.3 3579.1 3590.7 3608.9 3619.8 3622.3 4970.1 5013.4 5015.7 5055.3 5067.4 4502.3 4636.1 4640.3 4656.7 4662.8 5347.9 5394.9 5399.2 5401.4 5469.8 3758.3 3919.9 3940.5 3953.3 3974.6 5513.7 5616.2 5641.4 5643.3 5657.2 3947.4 3964.6 4003.7 4004.8 4037.5 3932.5 4075.8 4088.8 4102.1 4104.9 4597.7 4598.2 4609.2 4628.1 4633.7 5470.3 5486.8 5492.9 5493.6 5496.9 1363.8 1401.8 1413.0 1416.8 1419.6 864.1 886.4 887.6 888.8 902.8 5127.0 5208.9 5215.5 5224.3 5231.4 5612.3 5640.0 5652.5 5653.5 5661.8 5049.5 5199.2 5273.6 5274.1 5287.8 2942.4 2947.9 2965.3 2985.0 2993.7 5791.7 5811.3 5811.9 5852.6 5858.4 5584.5 5657.6 5689.9 5699.8 5713.8 5737.7 5810.9 5821.5 5831.3 5848.8 5376.0 5568.7 5628.4 5647.9 5649.9 5513.9 5544.0 5546.6 5551.7 5571.4 5777.0 5783.2 5794.0 5811.5 5814.2 3868.2 3921.2 3925.7 3968.7 3969.6 5552.1 5559.2 5588.2 5592.7 5635.8 5478.6 5522.3 5561.2 5577.6 5583.0 5207.5 5395.4 5416.4 5453.3 5470.9 5202.6 5264.2 5267.4 5320.9 5361.2 5370.3 5473.8 5484.7 5500.4 5502.3 5235.1 5334.1 5393.9 5421.0 5422.5 23:28:00 INFO - PROCESS | 5226 | 23:28:00 INFO - PROCESS | 5226 | [#5] yui.html Cycles:180 Average:21202.79 Median:4442.14 stddev:30791.55 (693.2%) stddev-sans-first:30839.34 23:28:00 INFO - PROCESS | 5226 | Values: 673.3 740.2 748.8 789.3 818.5 14322.2 15575.2 15835.2 15842.6 15869.2 14497.6 15452.2 16027.6 16155.4 16965.8 677.2 828.8 830.5 835.6 836.0 790.3 833.7 836.0 837.6 837.8 870.1 871.1 873.7 874.9 875.0 698.5 723.0 868.4 868.8 869.6 539.7 604.1 623.6 624.3 624.4 15571.0 15613.7 15619.6 15623.8 15699.7 13761.9 14061.3 14101.5 14186.4 14246.6 13335.2 13396.1 13423.8 13529.9 13585.8 4429.8 4454.5 4511.4 4593.7 4708.0 71954.3 72297.0 72504.6 73681.6 73682.6 10815.0 10993.8 11004.4 11147.9 11508.9 20491.0 20544.3 20583.9 20601.6 20616.1 8201.5 8252.4 8270.3 8272.3 8683.0 4501.5 4561.6 4599.2 4624.6 4780.8 3414.3 3510.6 3518.6 3520.2 3838.9 662.5 676.6 678.3 681.8 686.7 636.5 685.4 762.1 762.5 770.6 10159.9 10442.7 10518.2 10581.8 10692.3 627.9 646.8 703.0 760.0 765.6 4731.5 4871.6 4957.7 4977.3 4995.0 115427.0 119493.0 119921.0 120283.0 124315.4 101636.5 102118.5 102610.5 103540.5 105556.5 86575.6 87628.6 87705.6 87754.0 88102.0 9489.9 9604.3 9748.3 9748.6 9802.7 53265.0 53366.5 53710.0 53826.0 55309.7 73407.0 74854.3 78468.0 81150.0 81539.2 12432.4 12500.3 12707.1 12710.2 12887.9 13615.5 13974.2 14244.5 14259.9 14625.2 13248.0 13787.0 13956.2 13971.9 14054.3 12994.7 13122.1 13212.1 13379.9 13612.7 12998.4 13477.7 13496.2 13623.7 13630.0 10075.7 10783.6 10884.4 10947.3 10949.9 10650.5 11180.0 11223.0 11292.7 11305.8 23:28:00 INFO - PROCESS | 5226 | -------- Summary: end -------- 23:28:00 INFO - PROCESS | 5226 | 23:28:01 INFO - TEST-INFO | 5226: exit 0 23:28:01 INFO - TEST-OK | dromaeo_css | took 1113281ms 23:28:01 INFO - TEST-START | kraken 23:28:01 INFO - operating with platform_type : linux_ 23:28:01 INFO - Initialising browser for kraken test... 23:28:01 INFO - TEST-INFO | started process 5407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphL1s9y/profile http://localhost:42247/getInfo.html) 23:28:05 INFO - PROCESS | 5407 | __metrics Screen width/height:1600/1200 23:28:05 INFO - PROCESS | 5407 | colorDepth:24 23:28:05 INFO - PROCESS | 5407 | Browser inner width/height: 1024/697 23:28:05 INFO - PROCESS | 5407 | __metrics 23:28:06 ERROR - PROCESS | 5407 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:28:06 INFO - PROCESS | 5407 | console.error: PushService: 23:28:06 INFO - PROCESS | 5407 | stateChangeProcessEnqueue: Error transitioning state 23:28:06 INFO - PROCESS | 5407 | UnknownError 23:28:07 INFO - TEST-INFO | 5407: exit 0 23:28:07 INFO - Browser initialized. 23:28:07 INFO - Running cycle 1/1 for kraken test... 23:28:07 INFO - TEST-INFO | started process 5486 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphL1s9y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:19 INFO - PROCESS | 5486 | RSS: Main: 156372992 23:28:19 INFO - PROCESS | 5486 | 23:28:21 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42247/tests/kraken/driver.html?testName=audio-beat-detection) 23:28:22 INFO - PROCESS | 5486 | RSS: Main: 214265856 23:28:22 INFO - PROCESS | 5486 | 23:28:25 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42247/tests/kraken/driver.html?testName=audio-dft) 23:28:25 INFO - PROCESS | 5486 | RSS: Main: 203194368 23:28:25 INFO - PROCESS | 5486 | 23:28:28 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42247/tests/kraken/driver.html?testName=audio-fft) 23:28:29 INFO - PROCESS | 5486 | RSS: Main: 268926976 23:28:29 INFO - PROCESS | 5486 | 23:28:31 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42247/tests/kraken/driver.html?testName=audio-oscillator) 23:28:32 INFO - PROCESS | 5486 | RSS: Main: 179924992 23:28:32 INFO - PROCESS | 5486 | 23:28:34 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:28:34 INFO - PROCESS | 5486 | RSS: Main: 184123392 23:28:34 INFO - PROCESS | 5486 | 23:28:38 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42247/tests/kraken/driver.html?testName=imaging-darkroom) 23:28:38 INFO - PROCESS | 5486 | RSS: Main: 219418624 23:28:38 INFO - PROCESS | 5486 | 23:28:42 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42247/tests/kraken/driver.html?testName=imaging-desaturate) 23:28:42 INFO - PROCESS | 5486 | RSS: Main: 220102656 23:28:42 INFO - PROCESS | 5486 | 23:28:45 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42247/tests/kraken/driver.html?testName=json-parse-financial) 23:28:45 INFO - PROCESS | 5486 | RSS: Main: 220504064 23:28:45 INFO - PROCESS | 5486 | 23:28:47 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:28:48 INFO - PROCESS | 5486 | RSS: Main: 206757888 23:28:48 INFO - PROCESS | 5486 | 23:28:49 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:28:50 INFO - PROCESS | 5486 | RSS: Main: 211480576 23:28:50 INFO - PROCESS | 5486 | 23:28:52 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:28:53 INFO - PROCESS | 5486 | RSS: Main: 217251840 23:28:53 INFO - PROCESS | 5486 | 23:28:55 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:28:56 INFO - PROCESS | 5486 | RSS: Main: 222879744 23:28:56 INFO - PROCESS | 5486 | 23:28:59 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:28:59 INFO - PROCESS | 5486 | RSS: Main: 222482432 23:28:59 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | Cycle 1(1): loaded http://localhost:42247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42247/tests/kraken/driver.html?testName=ai-astar) 23:29:01 INFO - PROCESS | 5486 | RSS: Main: 264695808 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | __start_tp_report 23:29:01 INFO - PROCESS | 5486 | _x_x_mozilla_page_load 23:29:01 INFO - PROCESS | 5486 | _x_x_mozilla_page_load_details 23:29:01 INFO - PROCESS | 5486 | |i|pagename|runs| 23:29:01 INFO - PROCESS | 5486 | |0;ai-astar;111;105;110;111;112;111;110;113;109;111 23:29:01 INFO - PROCESS | 5486 | |1;audio-beat-detection;160;130;126;174;125;127;152;124;154;128 23:29:01 INFO - PROCESS | 5486 | |2;audio-dft;159;160;160;160;160;160;161;163;159;160 23:29:01 INFO - PROCESS | 5486 | |3;audio-fft;75;74;74;113;74;75;74;74;98;75 23:29:01 INFO - PROCESS | 5486 | |4;audio-oscillator;84;85;117;84;85;86;119;86;84;137 23:29:01 INFO - PROCESS | 5486 | |5;imaging-gaussian-blur;118;117;117;117;116;117;117;117;117;118 23:29:01 INFO - PROCESS | 5486 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;146;146 23:29:01 INFO - PROCESS | 5486 | |7;imaging-desaturate;95;95;95;94;95;95;95;95;95;95 23:29:01 INFO - PROCESS | 5486 | |8;json-parse-financial;52;51;52;52;52;53;52;51;51;53 23:29:01 INFO - PROCESS | 5486 | |9;json-stringify-tinderbox;60;60;60;59;60;60;60;60;59;61 23:29:01 INFO - PROCESS | 5486 | |10;stanford-crypto-aes;79;80;78;80;79;84;89;81;110;82 23:29:01 INFO - PROCESS | 5486 | |11;stanford-crypto-ccm;126;121;126;120;119;119;119;127;134;125 23:29:01 INFO - PROCESS | 5486 | |12;stanford-crypto-pbkdf2;159;164;164;161;159;160;157;162;159;162 23:29:01 INFO - PROCESS | 5486 | |13;stanford-crypto-sha256-iterative;63;63;63;62;64;62;61;63;61;62 23:29:01 INFO - PROCESS | 5486 | __end_tp_report 23:29:01 INFO - PROCESS | 5486 | __start_cc_report 23:29:01 INFO - PROCESS | 5486 | _x_x_mozilla_cycle_collect,1669 23:29:01 INFO - PROCESS | 5486 | __end_cc_report 23:29:01 INFO - PROCESS | 5486 | __startTimestamp1465280941503__endTimestamp 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | ------- Summary: start ------- 23:29:01 INFO - PROCESS | 5486 | Number of tests: 14 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.16 (1.9%) stddev-sans-first:2.28 23:29:01 INFO - PROCESS | 5486 | Values: 111.0 105.0 110.0 111.0 112.0 111.0 110.0 113.0 109.0 111.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:141.00 stddev:18.21 (12.9%) stddev-sans-first:17.82 23:29:01 INFO - PROCESS | 5486 | Values: 160.0 130.0 126.0 174.0 125.0 127.0 152.0 124.0 154.0 128.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 23:29:01 INFO - PROCESS | 5486 | Values: 159.0 160.0 160.0 160.0 160.0 160.0 161.0 163.0 159.0 160.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#3] audio-fft Cycles:10 Average:80.60 Median:74.50 stddev:13.60 (18.3%) stddev-sans-first:14.27 23:29:01 INFO - PROCESS | 5486 | Values: 75.0 74.0 74.0 113.0 74.0 75.0 74.0 74.0 98.0 75.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:84.50 stddev:19.78 (23.4%) stddev-sans-first:20.44 23:29:01 INFO - PROCESS | 5486 | Values: 84.0 85.0 117.0 84.0 85.0 86.0 119.0 86.0 84.0 137.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 23:29:01 INFO - PROCESS | 5486 | Values: 118.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 118.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 23:29:01 INFO - PROCESS | 5486 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:29:01 INFO - PROCESS | 5486 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 23:29:01 INFO - PROCESS | 5486 | Values: 52.0 51.0 52.0 52.0 52.0 53.0 52.0 51.0 51.0 53.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 23:29:01 INFO - PROCESS | 5486 | Values: 60.0 60.0 60.0 59.0 60.0 60.0 60.0 60.0 59.0 61.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:80.50 stddev:9.61 (11.9%) stddev-sans-first:10.01 23:29:01 INFO - PROCESS | 5486 | Values: 79.0 80.0 78.0 80.0 79.0 84.0 89.0 81.0 110.0 82.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:125.50 stddev:4.90 (3.9%) stddev-sans-first:5.12 23:29:01 INFO - PROCESS | 5486 | Values: 126.0 121.0 126.0 120.0 119.0 119.0 119.0 127.0 134.0 125.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:161.50 stddev:2.31 (1.4%) stddev-sans-first:2.37 23:29:01 INFO - PROCESS | 5486 | Values: 159.0 164.0 164.0 161.0 159.0 160.0 157.0 162.0 159.0 162.0 23:29:01 INFO - PROCESS | 5486 | 23:29:01 INFO - PROCESS | 5486 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 23:29:01 INFO - PROCESS | 5486 | Values: 63.0 63.0 63.0 62.0 64.0 62.0 61.0 63.0 61.0 62.0 23:29:01 INFO - PROCESS | 5486 | -------- Summary: end -------- 23:29:01 INFO - PROCESS | 5486 | 23:29:02 INFO - TEST-INFO | 5486: exit 0 23:29:02 INFO - TEST-OK | kraken | took 60876ms 23:29:02 INFO - SUITE-END | took 1174s 23:29:02 INFO - Completed test suite (00:19:34) 23:29:02 INFO - javascript benchmark 23:29:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3088.7837851350414, 3096.086654436941, 3109.8911538096163, 3128.890488832891, 3138.795978261413, 5143.074246635604, 5153.30430391897, 5277.208418737191, 5410.756515956781, 5424.10502267126, 5298.337707786527, 5306.787728490865, 5317.335333083364, 5340.49265319795, 5384.219288203209, 4766.189747742875, 4813.253945638432, 4855.417349918015, 4865.318855360252, 5035.697858128506, 4932.7780249888665, 4962.478939711333, 4966.627502937276, 5005.174146265862, 5019.322391477141, 4704.811807527695, 4753.47711751707, 4765.7140571565815, 4842.225243960949, 4858.927116093261, 4831.541003604652, 4856.562909338141, 4873.122837889178, 4914.140025495524, 4964.553190212862, 4869.732164730938, 4892.192788189945, 4899.436564795059, 4902.338184345103, 4903.239997800347, 5091.8981620367385, 5212.4266330703595, 5299.205119232108, 5337.0, 5367.302250707383, 5763.164341170507, 5787.826365209051, 5792.044312688401, 5810.854728631776, 5815.563832712563, 5507.311586051744, 5793.391693872125, 5826.708664566755, 5829.0, 5840.0, 5253.0544501989625, 5318.627696061278, 5319.813806516753, 5401.756920938567, 5402.216678581623, 5907.497862681635, 5927.229460170237, 5930.495907847795, 5933.169356290123, 5934.258217722785, 5417.512423881791, 5555.0, 5615.157726341082, 5642.915356269659, 5648.63283886546, 5664.801731939362, 5727.140928860705, 5777.277840269966, 5777.508911742465, 5783.0457974434585, 5689.630174038674, 5698.572607054488, 5700.486956173964, 5700.800471983461, 5703.287089113861, 5498.395176530578, 5623.887522249532, 5667.744951477193, 5685.403032681592, 5696.031674615326, 4327.459067616548, 4342.6742994275555, 4346.630536404441, 4382.408374869352, 4384.627306678968, 3874.438206460104, 3888.0862997195636, 3891.377379619304, 3924.9803750981064, 3926.2540117377607, 5099.56653684441, 5196.220566914917, 5279.366476022825, 5345.438728933406, 5350.9732451337495, 5728.341240757283, 5739.62692424991, 5748.511376533042, 5787.565932555075, 5805.01314776489, 5346.705931173823, 5346.786128554814, 5353.678779273256, 5354.678719276856, 5356.437574054668, 4629.583337499641, 4748.596369308579, 4776.259679749644, 4782.995570930074, 4832.8791780205775, 5893.174955506147, 5903.26209223847, 5906.291245050621, 5906.7637294508595, 5919.378465261172, 5752.367239603724, 5761.827145185651, 5771.076627739541, 5775.942240577709, 5776.018076927016, 5648.519875810603, 5868.383819699041, 5915.260592425947, 5928.614640048383, 5949.434803693643, 5616.971915140398, 5633.0705433604635, 5635.943640563706, 5643.746031428677, 5654.943450565606, 5499.560035197094, 5686.232358631697, 5732.34078081026, 5748.885022299614, 5749.683767392667, 5624.634398764068, 5640.971795140998, 5649.209110724584, 5672.688002159921, 5700.173474846207, 5596.328440586993, 5605.523530499953, 5608.635438696471, 5612.298462692163, 5616.803411880564, 5250.0, 5356.5982551308025, 5414.972925135349, 5481.096417694218, 5485.670859748428, 5519.254900588369, 5675.77296908119, 5684.0, 5690.886182276414, 5711.942880571307, 5529.087700529368, 5530.115181570929, 5530.446955304437, 5530.5022547970875, 5574.219609254626], "value": 5260.907146693576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.1021971054722, 596.2665920917269, 617.120603140525, 621.8414304352391, 639.3095456906541, 31422.84288578557, 34606.0, 34632.653673463225, 34638.82680586597, 42300.0, 40872.0, 45153.54846451535, 45370.546294537045, 45399.77300113495, 45413.31880021794, 8832.381733278673, 9055.0, 9061.50161741104, 9064.501452420114, 9066.773330666752, 8746.781330466725, 8907.643694252254, 8911.465312081264, 8914.33142514314, 8918.55407229636, 8918.063603321654, 8920.46477211366, 8923.643054277822, 8952.910470895273, 8954.731358059269, 8892.333075019367, 8928.464292142493, 8946.329025323126, 8948.105189481064, 8953.641854325884, 8802.691905783331, 8869.822603547893, 8875.556222188929, 8895.866562001574, 8895.955520222422, 41715.791421043, 42214.57785422168, 42247.366289505684, 42272.78863605693, 42310.0, 3482.82585870708, 3943.7042221833476, 4018.548680324149, 4127.814248358831, 4270.316749320094, 32185.51721724176, 32373.352532949444, 32919.50620740691, 32921.83539082289, 32928.83535582331, 4286.057067445157, 4313.568643135693, 4319.136172765459, 4364.6290065344165, 4558.452985641711, 350579.0, 352062.0, 352679.0, 353123.0, 353973.0, 57199.0, 57392.0, 57721.4227857716, 57750.71124644434, 57846.4215357841, 35182.64817351845, 35891.641083589355, 35952.28095438077, 37974.0, 38030.0, 16582.17089145538, 16815.747763783555, 16839.0, 16881.493555193363, 16900.492985210465, 12672.80990785139, 12749.745005099847, 12751.107422480338, 12755.0, 12759.043071769654, 19365.8063419364, 19522.731022483495, 19558.0, 19665.230129288433, 19771.901140494207, 7038.683259253346, 7174.1032370953635, 7234.095738032746, 7286.214877355082, 7408.85182296351, 987.2792861211356, 1062.351965301181, 1103.8564986551696, 1111.7630095166112, 1120.2214460949563, 33305.16737081592, 33390.833045835105, 33397.0, 33580.49629255563, 33869.49195762065, 1136.6192325570862, 1146.7821113988316, 1184.7097461121907, 1215.1129675337054, 1223.9694007649703, 16947.0, 17324.740128898076, 17438.65122697539, 17500.21249043796, 17562.473125806246, 566246.0, 571992.0, 572528.0, 573191.0, 576456.0, 58465.415345846, 58931.0, 58982.70508647429, 58999.0, 59126.704366478756, 1250.5623031938749, 1290.8450985881566, 1344.9327533623475, 1369.233229391544, 1370.7052983608571, 30718.84640576783, 34545.48181777326, 34966.47550286697, 34976.0, 35284.0, 51340.0, 51711.0, 52005.0, 52024.0, 52040.47959520508, 330350.0, 330521.0, 331330.0, 332414.0, 332647.0, 27832.443351133268, 27849.44301114007, 28330.433391331644, 29017.12948611545, 29089.563656545593, 49732.25401619051, 50039.0, 50235.74882125566, 51236.23145652893, 51284.48715512797, 40321.395179072926, 41286.79356603198, 41853.58146418497, 42205.36691949562, 42211.577884220766, 40994.0, 41038.38442423426, 41512.792436038835, 42465.15069698651, 42509.57490425056, 34672.82663586666, 35640.108997275274, 42110.57889421189, 42335.36496952487, 43084.569154308054, 36438.453423198145, 38191.80904095462, 38259.0, 38768.224635506565, 38819.41770873496, 28319.0, 28919.0, 28962.565561517014, 29135.417291654474, 29175.416491670472], "value": 19942.002650791466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.926673931953, 389.4878235120816, 389.5153023868797, 392.57601790066724, 411.4918076175922, 14376.424943002294, 16131.596710082224, 17793.822061779378, 19286.035698215084, 19318.227270909163, 14907.478238261663, 17422.738658920127, 18304.542386440346, 18427.631447371044, 18456.169472373742, 5544.22380866679, 5603.131514615241, 5848.795292164775, 5857.824265272027, 5900.203472531196, 3837.616238376168, 5405.729713514328, 5765.4522820332, 5802.158686990384, 5823.417658234164, 5679.0, 5740.56945729072, 5784.884302313952, 5793.333766616851, 5798.043322851724, 5759.4816466518005, 5767.480926716595, 5933.27934898881, 6320.304766475684, 6340.936590634081, 5607.691576963306, 5628.26832511775, 5640.605157638967, 5647.237622920935, 5669.9433005669825, 12839.422225999853, 13424.0, 13472.595822125351, 13502.527411540548, 13504.054716169872, 2191.3316438486254, 2197.198022721695, 2277.851939623919, 2438.6463962725306, 2717.6467059282363, 9056.366054376196, 9209.769755756093, 9488.430694158373, 9538.809223815555, 9661.372010819345, 1845.1973391574709, 1871.9532011699816, 1875.6623807714607, 1938.2150229157214, 1975.8715683480527, 78359.90660607099, 82054.17945820525, 82630.0, 82922.0, 84027.15972840255, 10566.260361774603, 10571.10145637614, 10878.075363594025, 10949.343039417661, 11027.503762330714, 14978.17620030887, 15099.622509437133, 15150.54548363551, 15158.242087895782, 15160.393584256726, 7012.193597736224, 7358.816029599303, 7686.192949740197, 7784.688612455551, 7913.44605877583, 7332.26996311441, 7374.151972523121, 7548.811279718051, 7685.884711729217, 7906.446548741648, 4626.1441633297945, 4640.025594625099, 4741.1465936131135, 4797.328374027569, 4815.061063092733, 1781.5189898727272, 1852.4998250472568, 1901.4390754727388, 1967.7441932548677, 2019.8687085339407, 905.2848249882519, 924.4360939826701, 1017.59296281488, 1069.5187165775433, 1130.2653275371074, 18305.359312424, 18643.90678046628, 18646.627067458845, 18928.621427571372, 18965.336213232746, 972.1250874213265, 988.2341185581089, 1003.6286573967678, 1009.4700282351824, 1026.6047571684805, 10242.948785256027, 10405.167586593054, 10516.526756295985, 10553.63062292816, 10554.788904221872, 494645.0, 499976.0, 508406.0, 514181.0, 514389.0, 92953.53523232341, 93639.0, 94133.52933235427, 95018.0, 96284.51857740666, 985.4186030242191, 990.1435258501344, 993.6103670672045, 995.2237254941012, 996.9451680157661, 16356.182190890646, 16428.835711642732, 16507.257173427464, 16635.750463743294, 17312.826871731122, 42027.57972420237, 43173.352399714655, 43244.56755432405, 45334.77332613448, 45377.0, 57757.42242577521, 58347.0, 58522.0, 59613.0, 59629.7018514922, 8230.670773169006, 8538.91461085406, 8570.142985701379, 8718.43330183536, 8757.562121893752, 11754.529818807152, 12692.238465692088, 12791.744165116459, 13346.733065338445, 13352.132111412728, 11403.0, 11510.194286399872, 11564.653060408202, 11579.594714184963, 11788.351640659846, 11131.45879593829, 11230.0, 11351.432428378714, 11502.827457587975, 11644.6506604802, 11492.195546311677, 11888.405579721153, 11965.581204657796, 12030.75938481208, 12096.274223546616, 8500.787480313042, 8513.404061715868, 8515.148485151436, 8529.445586036887, 8714.215720585178, 10823.242373033812, 11031.0, 11542.59600913964, 11629.59296424621, 12171.452284647035], "value": 8955.523121371794, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.4154513559165, 1463.1075044223026, 1506.4953240664377, 1645.3089702325021, 1807.557148498618, 7893.723719669812, 8296.543690097042, 8309.293710034646, 8474.74575762728, 8492.065872753992, 8250.422470427058, 8459.196376344238, 8460.788480287996, 8462.87305690415, 8467.491950482976, 1897.0609548273555, 1952.5606738483868, 2206.580749657568, 2286.0284379138843, 2315.0276883708884, 2309.191782875997, 2358.84667496613, 2371.8576885386847, 2372.9406764830846, 2374.442006128558, 2266.8186545076355, 2286.6684330772027, 2290.0954123121355, 2291.106468477295, 2317.44381348476, 1909.6180763847217, 2126.8617539860015, 2198.0108950972126, 2220.500387412835, 2258.7515373308925, 2249.5275992041693, 2272.091163534599, 2272.125231785758, 2272.977270227293, 2275.6245219538755, 8155.877661835078, 8177.386695997824, 8193.34453243745, 8200.302974247195, 8205.0, 4845.345878306419, 4967.850964471072, 4968.0, 4970.577500912426, 4972.925406118911, 3078.722914937644, 3131.312632426362, 3191.521271809224, 3217.8391080446117, 3231.224506118538, 1007.5163921317809, 1022.3405903192473, 1033.452270296744, 1056.4717641179411, 1072.544168728295, 11501.539938402453, 11623.302601843916, 11637.0, 11678.299302041905, 11768.823467647991, 4421.049474363027, 4452.376667266585, 4572.7942242599165, 4575.519570445122, 4680.836170734008, 9316.161545460805, 9359.438433694, 9432.245420366355, 9433.198178154797, 9441.291903107294, 4973.229149481836, 4980.551750342486, 4985.875353116201, 4987.551120399109, 4993.775280112331, 3889.0, 3943.093088589583, 4450.087732014929, 4571.497135315112, 4679.415073115861, 3606.891793246207, 3718.1076541630355, 3720.4419337099653, 3775.6413140751583, 3806.9048273792823, 1116.7598966222301, 1129.5481807277156, 1135.0635725526474, 1139.5840518210794, 1141.2866958151155, 999.5406706208871, 1007.561710655867, 1009.1724785675822, 1014.1886490807325, 1019.1999280564721, 5893.528517718572, 5918.082697181943, 6018.21763170785, 6048.243969503812, 6110.480609148183, 460.34400978605356, 472.6951116529819, 478.6410192355733, 481.04992639537335, 482.3319702212391, 3964.60353964607, 4038.394240863835, 4059.2084543513733, 4069.145479449349, 4141.710080294411, 9617.086376794194, 10159.898401016044, 10182.745431364126, 10192.388456692623, 10194.3373680712, 9924.057214564606, 10006.899931000597, 10051.748706282255, 10075.848862267072, 10094.848577271347, 7764.417668674759, 7819.609019549, 7839.25527074927, 7844.9607751960875, 7856.528608283521, 5757.078867381367, 5771.076627739541, 5842.766289348378, 5870.236869206976, 5909.0, 9420.246380289424, 9458.668946586837, 9578.568964396758, 9586.808263834824, 9593.952030240083, 9179.219766319937, 9504.619815207314, 9629.614815407585, 9693.70918872435, 9694.418334899929, 6807.421369183675, 7169.175544812413, 7345.338919497271, 7417.295356941082, 7649.847003059796, 8217.630206640595, 8226.506409615442, 8237.176282371714, 8288.129746376544, 8309.542975136426, 7130.500864939612, 7315.670794814139, 7482.3639990601405, 7579.886301705368, 7604.163542010264, 7103.218645949045, 7339.559626422431, 7370.373518251009, 7506.099268087864, 7510.173880873209, 6498.902516462352, 6515.6742162892615, 6541.738330466728, 6578.703958321983, 6582.341765823571, 4984.551390374883, 5017.448080711192, 5085.5931525477135, 5199.0, 5218.452062533531, 5201.7399130044105, 5366.731663416892, 5385.380681221553, 5419.467585467246, 5496.862578435571], "value": 4360.28769257731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.5232760170151, 333.8063922924704, 339.21641009268586, 340.3057762165183, 341.1488336600183, 5243.898781255936, 5261.105612045952, 5285.2600635911, 5295.655782374143, 5297.973510132453, 5136.743162841862, 5142.588592912567, 5153.3558305211845, 5172.586193104552, 5252.606054545905, 3876.8449262029485, 3911.9413208801748, 4011.438398624195, 4039.5758445363253, 4076.51081870176, 4013.5785742497055, 4049.129437171008, 4050.10897602527, 4062.476571285768, 4064.817083231247, 3845.0582455859753, 3981.741186822862, 3984.482017337742, 3999.860004899829, 4004.3993400989793, 3756.643118903727, 3936.2127574485216, 3939.9409008864745, 3989.5264935739738, 4064.329385651364, 3579.1052236940764, 3590.7127429805555, 3608.9097772555633, 3619.7647152934974, 3622.3117607654726, 4970.13022721026, 5013.423456302536, 5015.749212539358, 5055.3317405256175, 5067.442581316052, 4502.324651302298, 4636.142313671948, 4640.303954406866, 4656.696125084982, 4662.8134874604975, 5347.893042139174, 5394.867077913643, 5399.163129714901, 5401.405845357008, 5469.808556700496, 3758.342290099222, 3919.9041246379593, 3940.54683711374, 3953.3476976298875, 3974.5627980922077, 5513.696746678931, 5616.241807355996, 5641.407652196543, 5643.294588176519, 5657.23627310312, 3947.388154836005, 3964.643182113566, 4003.6997225208224, 4004.799760011988, 4037.4549435826375, 3932.508436445444, 4075.816588253536, 4088.836446542105, 4102.118044620421, 4104.938425923614, 4597.735622703716, 4598.2412901871485, 4609.239475486507, 4628.074385122989, 4633.72197668141, 5470.288862447856, 5486.753096110685, 5492.862678432991, 5493.560515158778, 5496.862578435491, 1363.8022486739367, 1401.8387885393106, 1412.971740565183, 1416.8016477693131, 1419.6237996930822, 864.1229152410309, 886.3529623374843, 887.6138879587401, 888.8311220868014, 902.8149229407956, 5126.974365128225, 5208.947910520922, 5215.478452154754, 5224.294720212799, 5231.424543300234, 5612.270404847343, 5639.956608027528, 5652.463016013472, 5653.491185793298, 5661.7735290588735, 5049.520295571915, 5199.16765755055, 5273.551748101454, 5274.12976858814, 5287.841364759063, 2942.411517696469, 2947.882084716587, 2965.258685328668, 2984.9701502984694, 2993.730564249228, 5791.739371728282, 5811.27359080115, 5811.854703632358, 5852.590318677737, 5858.384869588717, 5584.52531534816, 5657.632253903483, 5689.943100569024, 5699.800506982236, 5713.771449141987, 5737.713114344266, 5810.912836307374, 5821.476067153976, 5831.271091113526, 5848.824535263949, 5375.973120134374, 5568.721563921868, 5628.437156284339, 5647.915281270702, 5649.887002259849, 5513.889722205614, 5544.0, 5546.55627549787, 5551.666899986014, 5571.415001424954, 5776.971115144397, 5783.219265399117, 5793.971030144991, 5811.476967072984, 5814.215080964016, 3868.1876805871084, 3921.1569512553797, 3925.743761996134, 3968.722189446711, 3969.563348031772, 5552.056150454352, 5559.19391688211, 5588.161775733673, 5592.713675854658, 5635.802746903839, 5478.589105817, 5522.282103326703, 5561.249231353715, 5577.637453565505, 5583.0, 5207.479252074762, 5395.381385584387, 5416.431274716098, 5453.291072160594, 5470.890582188414, 5202.635815492879, 5264.210368444611, 5267.420583735862, 5320.866978325573, 5361.195820626937, 5370.301860758076, 5473.781048758005, 5484.698341591251, 5500.4224556420995, 5502.312210973629, 5235.110031294613, 5334.146536554133, 5393.946060539502, 5421.0, 5422.48486393792], "value": 4155.180262540454, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.3303495947087, 740.2116745665869, 748.7985323149938, 789.2975252025697, 818.4598165210961, 14322.212278324689, 15575.221238938051, 15835.208239588032, 15842.60393490162, 15869.206539673, 14497.5650730478, 15452.227388630552, 16027.599310017255, 16155.353785848582, 16965.83034169658, 677.1535580524345, 828.8093738440148, 830.489249111795, 835.5989125219899, 836.0427310729211, 790.3163763344694, 833.7415401225609, 835.9540225287637, 837.5560952695051, 837.7863644770575, 870.1124852650292, 871.0505548951674, 873.681106396164, 874.9431286966359, 874.995625021874, 698.50755217572, 723.0058669329671, 868.4355169140047, 868.8132051608903, 869.6347534035689, 539.6761942834307, 604.0546544657641, 623.6008954269271, 624.272722278545, 624.3600309682566, 15571.0, 15613.68772624541, 15619.609509762347, 15623.843761562353, 15699.686006279924, 13761.862381376159, 14061.296935153303, 14101.506447274296, 14186.43254269828, 14246.572602821932, 13335.199888006653, 13396.129251598712, 13423.798643020362, 13529.864701352959, 13585.796213056812, 4429.756363400012, 4454.532274111204, 4511.368408422823, 4593.655475839292, 4707.976460117678, 71954.28045719529, 72297.0, 72504.6374768128, 73681.63159184223, 73682.63158684225, 10815.0, 10993.780124397466, 11004.39475828821, 11147.888521114848, 11508.884911150948, 20491.0, 20544.280950166973, 20583.8970805145, 20601.587968240852, 20616.07227674758, 8201.548914809624, 8252.381071419548, 8270.338372930271, 8272.33821294295, 8683.0, 4501.459824820977, 4561.589456948891, 4599.195140850405, 4624.560666736655, 4780.784864681028, 3414.3342048300838, 3510.596281427617, 3518.648135186461, 3520.1727594015388, 3838.865639702653, 662.4998126414622, 676.6109487044969, 678.2945736434152, 681.7886455198905, 686.7115811359241, 636.4908073541241, 685.4071228387409, 762.1273641680356, 762.4510352546158, 770.6300975531776, 10159.949200253952, 10442.68671939843, 10518.211134164969, 10581.788364232827, 10692.251542391958, 627.8650090230623, 646.8059582125437, 702.9964850175717, 759.9544027358377, 765.64780201107, 4731.4558825735485, 4871.585915197248, 4957.702537847741, 4977.32806071183, 4994.950050499521, 115427.0, 119493.0, 119921.0, 120283.0, 124315.37842310731, 101636.49181754045, 102118.4894075525, 102610.48694756479, 103540.48229758954, 105556.47221763842, 86575.56712216524, 87628.56185719158, 87705.56147219223, 87754.0, 88102.0, 9489.857652135086, 9604.327697061139, 9748.317617766972, 9748.561314740995, 9802.65690700822, 53265.0, 53366.46633533615, 53710.0, 53825.999999998436, 55309.723451384096, 73407.0, 74854.25145748473, 78468.0, 81150.0, 81539.18460815553, 12432.378381081091, 12500.312482813533, 12707.110502264753, 12710.173838700455, 12887.93556032214, 13615.523456679364, 13974.161550307013, 14244.501442449464, 14259.928700356846, 14625.195614240893, 13248.0, 13787.0, 13956.162630242217, 13971.93014034964, 14054.297285135906, 12994.675133121747, 13122.14706044104, 13212.141210821266, 13379.933100334436, 13612.701457790208, 12998.446142085371, 13477.663058423617, 13496.190228586316, 13623.727525449634, 13630.04589678713, 10075.697729068139, 10783.568657253936, 10884.40135792539, 10947.288426252268, 10949.890501094887, 10650.467476625983, 11179.987602231677, 11223.0, 11292.661220163409, 11305.830412543653], "value": 7651.892391370485, "unit": "score"}], "name": "dromaeo_css", "value": 7119.896105746875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 111.0, 112.0, 111.0, 110.0, 113.0, 109.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 130.0, 126.0, 174.0, 125.0, 127.0, 152.0, 124.0, 154.0, 128.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 160.0, 160.0, 160.0, 160.0, 161.0, 163.0, 159.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 113.0, 74.0, 75.0, 74.0, 74.0, 98.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 117.0, 84.0, 85.0, 86.0, 119.0, 86.0, 84.0, 137.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.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": [52.0, 51.0, 52.0, 52.0, 52.0, 53.0, 52.0, 51.0, 51.0, 53.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 59.0, 61.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 78.0, 80.0, 79.0, 84.0, 89.0, 81.0, 110.0, 82.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 121.0, 126.0, 120.0, 119.0, 119.0, 119.0, 127.0, 134.0, 125.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 164.0, 161.0, 159.0, 160.0, 157.0, 162.0, 159.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 64.0, 62.0, 61.0, 63.0, 61.0, 62.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1488.5000000000002, "alertThreshold": 2.0}]} 23:29:02 INFO - Return code: 0 23:29:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:29:02 ERROR - # TBPL SUCCESS # 23:29:02 INFO - Running post-action listener: _resource_record_post_action 23:29:02 INFO - ##### 23:29:02 INFO - ##### Finished run-tests step (success) 23:29:02 INFO - ##### 23:29:02 INFO - Running post-run listener: _resource_record_post_run 23:29:02 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 180224; Write bytes: 300605440; Read time: 132; Write time: 238076 23:29:02 INFO - TinderboxPrint: CPU usage
13.1% 23:29:02 INFO - TinderboxPrint: I/O read bytes / time
180,224 / 132 23:29:02 INFO - TinderboxPrint: I/O write bytes / time
300,605,440 / 238,076 23:29:02 INFO - TinderboxPrint: CPU idle
8,264.0 (86.9%) 23:29:02 INFO - TinderboxPrint: CPU user
1,206.8 (12.7%) 23:29:02 INFO - TinderboxPrint: Swap in / out
0 / 0 23:29:02 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 8228864; Read time: 0; Write time: 808 23:29:02 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 180224; Write bytes: 288579584; Read time: 132; Write time: 227800 23:29:03 INFO - Running post-run listener: _upload_blobber_files 23:29:03 INFO - Blob upload gear active. 23:29:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:29:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:29:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:29:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:29:03 INFO - (blobuploader) - INFO - Open directory for files ... 23:29:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:29:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:29:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:07 INFO - (blobuploader) - INFO - Done attempting. 23:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:08 INFO - (blobuploader) - INFO - Done attempting. 23:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:09 INFO - (blobuploader) - INFO - Done attempting. 23:29:09 INFO - (blobuploader) - INFO - Iteration through files over. 23:29:09 INFO - Return code: 0 23:29:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:29:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:29:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6ffa9dd729c3384ca9061ff740c279dea2cb7f1da8f9d1154374d823d6c4162e08a7caa209a7b4bc12d195b259cf6dc0f8f2fcd561e097c219943350bf29a83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee6d8da9dee1665ffea1506b43f443080e82dfbf132998dc9495226b0345dd55495bd61311d89d192eed65b62a74cfbe051ee11ec0790e9dfe124501e37e851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f8335159cdd033ad4eca5b359074737819342518d59eaf5ece0eb52b601944b5390f05804dd9ee0313f6c6aab1819685430f6a2dd59cd938ffba44596a4ffd5"} 23:29:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:29:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:29:09 INFO - Contents: 23:29:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6ffa9dd729c3384ca9061ff740c279dea2cb7f1da8f9d1154374d823d6c4162e08a7caa209a7b4bc12d195b259cf6dc0f8f2fcd561e097c219943350bf29a83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee6d8da9dee1665ffea1506b43f443080e82dfbf132998dc9495226b0345dd55495bd61311d89d192eed65b62a74cfbe051ee11ec0790e9dfe124501e37e851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f8335159cdd033ad4eca5b359074737819342518d59eaf5ece0eb52b601944b5390f05804dd9ee0313f6c6aab1819685430f6a2dd59cd938ffba44596a4ffd5"} 23:29:09 INFO - Running post-run listener: copy_logs_to_upload_dir 23:29:09 INFO - Copying logs to upload dir... 23:29:09 INFO - mkdir: /builds/slave/test/build/upload/logs 23:29:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.709703 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-06 23:29:09.283662) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 23:29:09.286997) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957 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/c6ffa9dd729c3384ca9061ff740c279dea2cb7f1da8f9d1154374d823d6c4162e08a7caa209a7b4bc12d195b259cf6dc0f8f2fcd561e097c219943350bf29a83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee6d8da9dee1665ffea1506b43f443080e82dfbf132998dc9495226b0345dd55495bd61311d89d192eed65b62a74cfbe051ee11ec0790e9dfe124501e37e851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f8335159cdd033ad4eca5b359074737819342518d59eaf5ece0eb52b601944b5390f05804dd9ee0313f6c6aab1819685430f6a2dd59cd938ffba44596a4ffd5"} 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.011283 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/c6ffa9dd729c3384ca9061ff740c279dea2cb7f1da8f9d1154374d823d6c4162e08a7caa209a7b4bc12d195b259cf6dc0f8f2fcd561e097c219943350bf29a83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ee6d8da9dee1665ffea1506b43f443080e82dfbf132998dc9495226b0345dd55495bd61311d89d192eed65b62a74cfbe051ee11ec0790e9dfe124501e37e851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f8335159cdd033ad4eca5b359074737819342518d59eaf5ece0eb52b601944b5390f05804dd9ee0313f6c6aab1819685430f6a2dd59cd938ffba44596a4ffd5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 23:29:09.312204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:29:09.312561) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1465279557.15410-267471957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004431 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:29:09.398590) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 23:29:09.398914) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 23:29:09.399215) ========= ========= Total master_lag: 0.23 =========