builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1466533654.24 results: success (0) buildid: 20160621101102 builduid: ebceeda7399c49dc996ef03ed65e4998 revision: 51377a64158941f89ed73f388ae437cfa494c030 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.235417) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.235902) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.236301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.251676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.251980) ========= 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=20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-21 11:27:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.9M=0s 2016-06-21 11:27:34 (49.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093929 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.360432) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.360831) ========= 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=20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018843 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.411378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:34.412385) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 51377a64158941f89ed73f388ae437cfa494c030 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 51377a64158941f89ed73f388ae437cfa494c030 --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=20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-21 11:27:34,697 truncating revision to first 12 chars 2016-06-21 11:27:34,697 Setting DEBUG logging. 2016-06-21 11:27:34,698 attempt 1/10 2016-06-21 11:27:34,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/51377a641589?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 11:27:34,979 unpacking tar archive at: mozilla-central-51377a641589/testing/mozharness/ program finished with exit code 0 elapsedTime=0.634746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:35.063895) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:35.064346) ========= script_repo_revision: 51377a64158941f89ed73f388ae437cfa494c030 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:35.064890) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:35.065268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 11:27:35.085899) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2016-06-21 11:27:35.086653) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:27:35 INFO - MultiFileLogger online at 20160621 11:27:35 in /builds/slave/test 11:27:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:27:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:27:35 INFO - {'append_to_log': False, 11:27:35 INFO - 'base_work_dir': '/builds/slave/test', 11:27:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:27:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:27:35 INFO - 'branch': 'Firefox-Non-PGO', 11:27:35 INFO - 'buildbot_json_path': 'buildprops.json', 11:27:35 INFO - 'config_files': ('talos/linux_config.py',), 11:27:35 INFO - 'default_actions': ('clobber', 11:27:35 INFO - 'read-buildbot-config', 11:27:35 INFO - 'download-and-extract', 11:27:35 INFO - 'populate-webroot', 11:27:35 INFO - 'create-virtualenv', 11:27:35 INFO - 'install', 11:27:35 INFO - 'run-tests'), 11:27:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:27:35 INFO - 'download_minidump_stackwalk': True, 11:27:35 INFO - 'download_symbols': 'ondemand', 11:27:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:27:35 INFO - 'tooltool.py': '/tools/tooltool.py', 11:27:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:27:35 INFO - '/tools/misc-python/virtualenv.py')}, 11:27:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:27:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:27:35 INFO - 'installer_path': 'installer.exe', 11:27:35 INFO - 'log_level': 'info', 11:27:35 INFO - 'log_name': 'talos', 11:27:35 INFO - 'log_to_console': True, 11:27:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:27:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:27:35 INFO - 'opt_config_files': (), 11:27:35 INFO - 'pip_index': False, 11:27:35 INFO - 'sps_profile': False, 11:27:35 INFO - 'sps_profile_interval': 0, 11:27:35 INFO - 'suite': 'dromaeojs', 11:27:35 INFO - 'system_bits': '32', 11:27:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:27:35 INFO - 'title': 'talos-linux64-ix-015', 11:27:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:27:35 INFO - 'use_talos_json': True, 11:27:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:27:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:27:35 INFO - 'work_dir': 'build'} 11:27:35 INFO - ##### 11:27:35 INFO - ##### Running clobber step. 11:27:35 INFO - ##### 11:27:35 INFO - Running pre-action listener: _resource_record_pre_action 11:27:35 INFO - Running main action method: clobber 11:27:35 INFO - rmtree: /builds/slave/test/build 11:27:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:27:35 INFO - Running post-action listener: _resource_record_post_action 11:27:35 INFO - ##### 11:27:35 INFO - ##### Finished clobber step (success) 11:27:35 INFO - ##### 11:27:35 INFO - ##### 11:27:35 INFO - ##### Running read-buildbot-config step. 11:27:35 INFO - ##### 11:27:35 INFO - Running pre-action listener: _resource_record_pre_action 11:27:35 INFO - Running main action method: read_buildbot_config 11:27:35 INFO - Using buildbot properties: 11:27:35 INFO - { 11:27:35 INFO - "project": "", 11:27:35 INFO - "product": "firefox", 11:27:35 INFO - "script_repo_revision": "production", 11:27:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 11:27:35 INFO - "repository": "", 11:27:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 11:27:35 INFO - "buildid": "20160621101102", 11:27:35 INFO - "pgo_build": "False", 11:27:35 INFO - "basedir": "/builds/slave/test", 11:27:35 INFO - "buildnumber": 616, 11:27:35 INFO - "slavename": "talos-linux64-ix-015", 11:27:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:27:35 INFO - "platform": "ubuntu64_hw", 11:27:35 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 11:27:35 INFO - "branch": "mozilla-central", 11:27:35 INFO - "repo_path": "mozilla-central", 11:27:35 INFO - "revision": "51377a64158941f89ed73f388ae437cfa494c030", 11:27:35 INFO - "stage_platform": "linux64", 11:27:35 INFO - "builduid": "ebceeda7399c49dc996ef03ed65e4998", 11:27:35 INFO - "slavebuilddir": "test" 11:27:35 INFO - } 11:27:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:27:35 INFO - Running post-action listener: _resource_record_post_action 11:27:35 INFO - ##### 11:27:35 INFO - ##### Finished read-buildbot-config step (success) 11:27:35 INFO - ##### 11:27:35 INFO - ##### 11:27:35 INFO - ##### Running download-and-extract step. 11:27:35 INFO - ##### 11:27:35 INFO - Running pre-action listener: _resource_record_pre_action 11:27:35 INFO - Running main action method: download_and_extract 11:27:35 INFO - mkdir: /builds/slave/test/build/tests 11:27:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:35 INFO - https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:27:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:27:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:27:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:36 INFO - Downloaded 1361 bytes. 11:27:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:27:36 INFO - Using the following test package requirements: 11:27:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:27:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:27:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:27:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'jsshell-linux-x86_64.zip'], 11:27:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:27:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:27:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:27:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:27:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:27:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:27:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:27:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:27:36 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')]} 11:27:36 INFO - https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:27:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:27:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:27:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:38 INFO - Downloaded 22658953 bytes. 11:27:38 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'] 11:27:38 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 11:27:39 INFO - Return code: 0 11:27:39 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 11:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:27:39 INFO - https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:27:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:27:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:27:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:41 INFO - Downloaded 22658953 bytes. 11:27:41 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'] 11:27:41 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 11:27:42 INFO - Return code: 0 11:27: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')]} 11:27:42 INFO - https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:27:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:27:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:27:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:27:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:28:06 INFO - Downloaded 14405384 bytes. 11:28:06 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'] 11:28:06 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 11:28:06 INFO - Return code: 0 11:28: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')]} 11:28:06 INFO - https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:28:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:28:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:28:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:28:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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 11:28:49 INFO - Downloaded 56240852 bytes. 11:28:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:28:49 INFO - mkdir: /builds/slave/test/properties 11:28:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:28:49 INFO - Writing to file /builds/slave/test/properties/build_url 11:28:49 INFO - Contents: 11:28:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:28:50 INFO - Running post-action listener: _resource_record_post_action 11:28:50 INFO - Running post-action listener: set_extra_try_arguments 11:28:50 INFO - ##### 11:28:50 INFO - ##### Finished download-and-extract step (success) 11:28:50 INFO - ##### 11:28:50 INFO - ##### 11:28:50 INFO - ##### Running populate-webroot step. 11:28:50 INFO - ##### 11:28:50 INFO - Running pre-action listener: _resource_record_pre_action 11:28:50 INFO - Running main action method: populate_webroot 11:28:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:28:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:28:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:28:50 INFO - '--tppagecycles', 11:28:50 INFO - '7'], 11:28:50 INFO - 'tests': ['tsvgm']}}, 11:28:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:28:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:28:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:28:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:28:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['damp', 'tps']}, 11:28:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'tests': ['damp', 'tps']}, 11:28:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['dromaeo_dom']}, 11:28:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:28:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['basic_compositor_video']}, 11:28:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:28:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['a11yr', 11:28:50 INFO - 'ts_paint', 11:28:50 INFO - 'tpaint', 11:28:50 INFO - 'sessionrestore', 11:28:50 INFO - 'sessionrestore_no_auto_restore', 11:28:50 INFO - 'tabpaint']}, 11:28:50 INFO - 'other-e10s': {'tests': ['a11yr', 11:28:50 INFO - 'ts_paint', 11:28:50 INFO - 'tpaint', 11:28:50 INFO - 'sessionrestore', 11:28:50 INFO - 'sessionrestore_no_auto_restore', 11:28:50 INFO - 'tabpaint']}, 11:28:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:28:50 INFO - 'ts_paint', 11:28:50 INFO - 'tpaint', 11:28:50 INFO - 'sessionrestore', 11:28:50 INFO - 'sessionrestore_no_auto_restore', 11:28:50 INFO - 'tabpaint']}, 11:28:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:28:50 INFO - 'ts_paint', 11:28:50 INFO - 'tpaint', 11:28:50 INFO - 'sessionrestore', 11:28:50 INFO - 'sessionrestore_no_auto_restore', 11:28:50 INFO - 'tabpaint']}, 11:28:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['a11yr', 11:28:50 INFO - 'ts_paint', 11:28:50 INFO - 'tpaint', 11:28:50 INFO - 'sessionrestore', 11:28:50 INFO - 'sessionrestore_no_auto_restore', 11:28:50 INFO - 'tabpaint']}, 11:28:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['a11yr', 11:28:50 INFO - 'ts_paint', 11:28:50 INFO - 'tpaint', 11:28:50 INFO - 'sessionrestore', 11:28:50 INFO - 'sessionrestore_no_auto_restore', 11:28:50 INFO - 'tabpaint']}, 11:28:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['tsvgx', 11:28:50 INFO - 'tsvgr_opacity', 11:28:50 INFO - 'tart', 11:28:50 INFO - 'tscrollx', 11:28:50 INFO - 'cart']}, 11:28:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:28:50 INFO - 'tsvgr_opacity', 11:28:50 INFO - 'tart', 11:28:50 INFO - 'tscrollx', 11:28:50 INFO - 'cart']}, 11:28:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'talos_options': ['--disable-e10s'], 11:28:50 INFO - 'tests': ['tp5o']}, 11:28:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'tests': ['tp5o']}, 11:28:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'talos_options': ['--disable-e10s', 11:28:50 INFO - '--xperf_path', 11:28:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:50 INFO - 'tests': ['tp5n']}, 11:28:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:50 INFO - 'talos_options': ['--xperf_path', 11:28:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:50 INFO - 'tests': ['tp5n']}}, 11:28:50 INFO - 'talos.zip': {'path': '', 11:28:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:28:50 INFO - Running post-action listener: _resource_record_post_action 11:28:50 INFO - ##### 11:28:50 INFO - ##### Finished populate-webroot step (success) 11:28:50 INFO - ##### 11:28:50 INFO - ##### 11:28:50 INFO - ##### Running create-virtualenv step. 11:28:50 INFO - ##### 11:28:50 INFO - Running pre-action listener: _resource_record_pre_action 11:28:50 INFO - Running main action method: create_virtualenv 11:28:50 INFO - Creating virtualenv /builds/slave/test/build/venv 11:28:50 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 11:28:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:28:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:28:50 INFO - Using real prefix '/usr' 11:28:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:28:51 INFO - Installing distribute.............................................................................................................................................................................................done. 11:28:53 INFO - Installing pip.................done. 11:28:53 INFO - Return code: 0 11:28:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:28: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', 'pip>=1.5'] in /builds/slave/test/build 11:28: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 pip>=1.5 11:28:53 INFO - Using env: {'DISPLAY': ':0', 11:28:53 INFO - 'HOME': '/home/cltbld', 11:28:53 INFO - 'LANG': 'en_US.UTF-8', 11:28:53 INFO - 'LANGUAGE': 'en_US:en', 11:28:53 INFO - 'LOGNAME': 'cltbld', 11:28:53 INFO - 'MAIL': '/var/mail/cltbld', 11:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:53 INFO - 'MOZ_NO_REMOTE': '1', 11:28:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:28:53 INFO - 'NO_EM_RESTART': '1', 11:28:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:53 INFO - 'PWD': '/builds/slave/test', 11:28:53 INFO - 'SHELL': '/bin/bash', 11:28:53 INFO - 'SHLVL': '1', 11:28:53 INFO - 'TERM': 'linux', 11:28:53 INFO - 'TMOUT': '86400', 11:28:53 INFO - 'USER': 'cltbld', 11:28:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:53 INFO - '_': '/tools/buildbot/bin/python'} 11:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:53 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 11:28:53 INFO - Cleaning up... 11:28:53 INFO - Return code: 0 11:28:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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', '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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:28: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:28: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 psutil>=3.1.1 11:28:53 INFO - Using env: {'DISPLAY': ':0', 11:28:53 INFO - 'HOME': '/home/cltbld', 11:28:53 INFO - 'LANG': 'en_US.UTF-8', 11:28:53 INFO - 'LANGUAGE': 'en_US:en', 11:28:53 INFO - 'LOGNAME': 'cltbld', 11:28:53 INFO - 'MAIL': '/var/mail/cltbld', 11:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:53 INFO - 'MOZ_NO_REMOTE': '1', 11:28:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:28:53 INFO - 'NO_EM_RESTART': '1', 11:28:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:53 INFO - 'PWD': '/builds/slave/test', 11:28:53 INFO - 'SHELL': '/bin/bash', 11:28:53 INFO - 'SHLVL': '1', 11:28:53 INFO - 'TERM': 'linux', 11:28:53 INFO - 'TMOUT': '86400', 11:28:53 INFO - 'USER': 'cltbld', 11:28:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:53 INFO - '_': '/tools/buildbot/bin/python'} 11:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:53 INFO - Downloading/unpacking psutil>=3.1.1 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:28:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:55 INFO - Installing collected packages: psutil 11:28:55 INFO - Running setup.py install for psutil 11:28:55 INFO - building 'psutil._psutil_linux' extension 11:28:55 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 11:28:55 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 11:28:56 INFO - building 'psutil._psutil_posix' extension 11:28:56 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 11:28:56 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 11:28:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:56 INFO - Successfully installed psutil 11:28:56 INFO - Cleaning up... 11:28:56 INFO - Return code: 0 11:28:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:56 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:28:56 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 11:28:56 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 11:28:56 INFO - Using env: {'DISPLAY': ':0', 11:28:56 INFO - 'HOME': '/home/cltbld', 11:28:56 INFO - 'LANG': 'en_US.UTF-8', 11:28:56 INFO - 'LANGUAGE': 'en_US:en', 11:28:56 INFO - 'LOGNAME': 'cltbld', 11:28:56 INFO - 'MAIL': '/var/mail/cltbld', 11:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:56 INFO - 'MOZ_NO_REMOTE': '1', 11:28:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:28:56 INFO - 'NO_EM_RESTART': '1', 11:28:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:56 INFO - 'PWD': '/builds/slave/test', 11:28:56 INFO - 'SHELL': '/bin/bash', 11:28:56 INFO - 'SHLVL': '1', 11:28:56 INFO - 'TERM': 'linux', 11:28:56 INFO - 'TMOUT': '86400', 11:28:56 INFO - 'USER': 'cltbld', 11:28:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:56 INFO - '_': '/tools/buildbot/bin/python'} 11:28:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:28:58 INFO - Installing collected packages: mozsystemmonitor 11:28:58 INFO - Running setup.py install for mozsystemmonitor 11:28:58 INFO - Successfully installed mozsystemmonitor 11:28:58 INFO - Cleaning up... 11:28:58 INFO - Return code: 0 11:28:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:28:58 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')]} 11:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:58 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')]} 11:28:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:58 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:28:58 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 11:28:58 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 11:28:58 INFO - Using env: {'DISPLAY': ':0', 11:28:58 INFO - 'HOME': '/home/cltbld', 11:28:58 INFO - 'LANG': 'en_US.UTF-8', 11:28:58 INFO - 'LANGUAGE': 'en_US:en', 11:28:58 INFO - 'LOGNAME': 'cltbld', 11:28:58 INFO - 'MAIL': '/var/mail/cltbld', 11:28:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:58 INFO - 'MOZ_NO_REMOTE': '1', 11:28:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:28:58 INFO - 'NO_EM_RESTART': '1', 11:28:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:28:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:58 INFO - 'PWD': '/builds/slave/test', 11:28:58 INFO - 'SHELL': '/bin/bash', 11:28:58 INFO - 'SHLVL': '1', 11:28:58 INFO - 'TERM': 'linux', 11:28:58 INFO - 'TMOUT': '86400', 11:28:58 INFO - 'USER': 'cltbld', 11:28:58 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:28:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:58 INFO - '_': '/tools/buildbot/bin/python'} 11:28:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:58 INFO - Downloading/unpacking jsonschema==2.5.1 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:28: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 11:29:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:29:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:29:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:00 INFO - Downloading functools32-3.2.3-2.tar.gz 11:29:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:29:00 INFO - warning: no files found matching '*.txt' 11:29:00 INFO - no previously-included directories found matching 'build' 11:29:00 INFO - no previously-included directories found matching 'dist' 11:29:00 INFO - no previously-included directories found matching '.git*' 11:29:00 INFO - Installing collected packages: jsonschema, functools32 11:29:01 INFO - Running setup.py install for functools32 11:29:01 INFO - warning: no files found matching '*.txt' 11:29:01 INFO - no previously-included directories found matching 'build' 11:29:01 INFO - no previously-included directories found matching 'dist' 11:29:01 INFO - no previously-included directories found matching '.git*' 11:29:01 INFO - Successfully installed jsonschema functools32 11:29:01 INFO - Cleaning up... 11:29:01 INFO - Return code: 0 11:29:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:29:01 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')]} 11:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:01 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')]} 11:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:01 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:29:01 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 11:29:01 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 11:29:01 INFO - Using env: {'DISPLAY': ':0', 11:29:01 INFO - 'HOME': '/home/cltbld', 11:29:01 INFO - 'LANG': 'en_US.UTF-8', 11:29:01 INFO - 'LANGUAGE': 'en_US:en', 11:29:01 INFO - 'LOGNAME': 'cltbld', 11:29:01 INFO - 'MAIL': '/var/mail/cltbld', 11:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:01 INFO - 'MOZ_NO_REMOTE': '1', 11:29:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:01 INFO - 'NO_EM_RESTART': '1', 11:29:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:01 INFO - 'PWD': '/builds/slave/test', 11:29:01 INFO - 'SHELL': '/bin/bash', 11:29:01 INFO - 'SHLVL': '1', 11:29:01 INFO - 'TERM': 'linux', 11:29:01 INFO - 'TMOUT': '86400', 11:29:01 INFO - 'USER': 'cltbld', 11:29:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:01 INFO - '_': '/tools/buildbot/bin/python'} 11:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:29:01 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 11:29:01 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 11:29:01 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 11:29:01 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 11:29:01 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 11:29:01 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 11:29:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:29:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:29:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:29:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:29:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:29:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:29:03 INFO - Downloading docopt-0.6.1.tar.gz 11:29:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:29:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:29:04 INFO - Running setup.py install for blobuploader 11:29:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:29:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:29:04 INFO - Running setup.py install for requests 11:29:04 INFO - Running setup.py install for docopt 11:29:04 INFO - Successfully installed blobuploader requests docopt 11:29:04 INFO - Cleaning up... 11:29:04 INFO - Return code: 0 11:29:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:04 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')]} 11:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:04 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')]} 11:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:04 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:29:04 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 11:29:04 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 11:29:04 INFO - Using env: {'DISPLAY': ':0', 11:29:04 INFO - 'HOME': '/home/cltbld', 11:29:04 INFO - 'LANG': 'en_US.UTF-8', 11:29:04 INFO - 'LANGUAGE': 'en_US:en', 11:29:04 INFO - 'LOGNAME': 'cltbld', 11:29:04 INFO - 'MAIL': '/var/mail/cltbld', 11:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:04 INFO - 'MOZ_NO_REMOTE': '1', 11:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:04 INFO - 'NO_EM_RESTART': '1', 11:29:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:04 INFO - 'PWD': '/builds/slave/test', 11:29:04 INFO - 'SHELL': '/bin/bash', 11:29:04 INFO - 'SHLVL': '1', 11:29:04 INFO - 'TERM': 'linux', 11:29:04 INFO - 'TMOUT': '86400', 11:29:04 INFO - 'USER': 'cltbld', 11:29:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:04 INFO - '_': '/tools/buildbot/bin/python'} 11:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:04 INFO - Running setup.py (path:/tmp/pip-jxUYjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:04 INFO - Running setup.py (path:/tmp/pip-UcGBUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:05 INFO - Running setup.py (path:/tmp/pip-5pgYe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:05 INFO - Running setup.py (path:/tmp/pip-nAsmxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:05 INFO - Running setup.py (path:/tmp/pip-1EToIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:05 INFO - Running setup.py (path:/tmp/pip-ZccdiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:05 INFO - Running setup.py (path:/tmp/pip-5C9Oh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:05 INFO - Running setup.py (path:/tmp/pip-33uA7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:05 INFO - Running setup.py (path:/tmp/pip-ofTEp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:05 INFO - Running setup.py (path:/tmp/pip-ImThQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:05 INFO - Running setup.py (path:/tmp/pip-AhSOch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:05 INFO - Running setup.py (path:/tmp/pip-nqbMg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:05 INFO - Running setup.py (path:/tmp/pip-7eUZMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:05 INFO - Running setup.py (path:/tmp/pip-k4Zn4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:05 INFO - Running setup.py (path:/tmp/pip-dCChKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:06 INFO - Running setup.py (path:/tmp/pip-vv0xgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:06 INFO - Running setup.py (path:/tmp/pip-knE56P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:29:06 INFO - Running setup.py install for manifestparser 11:29:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozcrash 11:29:06 INFO - Running setup.py install for mozdebug 11:29:06 INFO - Running setup.py install for mozdevice 11:29:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozfile 11:29:06 INFO - Running setup.py install for mozhttpd 11:29:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozinfo 11:29:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozInstall 11:29:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:29:06 INFO - Running setup.py install for mozleak 11:29:07 INFO - Running setup.py install for mozlog 11:29:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for moznetwork 11:29:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for mozprocess 11:29:07 INFO - Running setup.py install for mozprofile 11:29:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for mozrunner 11:29:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Running setup.py install for mozscreenshot 11:29:07 INFO - Running setup.py install for moztest 11:29:07 INFO - Running setup.py install for mozversion 11:29:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:29:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:29:07 INFO - Cleaning up... 11:29:07 INFO - Return code: 0 11:29:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:07 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')]} 11:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:07 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')]} 11:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:07 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:29:07 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 11:29:07 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 11:29:07 INFO - Using env: {'DISPLAY': ':0', 11:29:07 INFO - 'HOME': '/home/cltbld', 11:29:07 INFO - 'LANG': 'en_US.UTF-8', 11:29:07 INFO - 'LANGUAGE': 'en_US:en', 11:29:07 INFO - 'LOGNAME': 'cltbld', 11:29:07 INFO - 'MAIL': '/var/mail/cltbld', 11:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:07 INFO - 'MOZ_NO_REMOTE': '1', 11:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:07 INFO - 'NO_EM_RESTART': '1', 11:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:07 INFO - 'PWD': '/builds/slave/test', 11:29:07 INFO - 'SHELL': '/bin/bash', 11:29:07 INFO - 'SHLVL': '1', 11:29:07 INFO - 'TERM': 'linux', 11:29:07 INFO - 'TMOUT': '86400', 11:29:07 INFO - 'USER': 'cltbld', 11:29:07 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:07 INFO - '_': '/tools/buildbot/bin/python'} 11:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:08 INFO - Running setup.py (path:/tmp/pip-O2o0ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:08 INFO - Running setup.py (path:/tmp/pip-rYIbF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:08 INFO - Running setup.py (path:/tmp/pip-Ys1fGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:08 INFO - Running setup.py (path:/tmp/pip-7yHKhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:08 INFO - Running setup.py (path:/tmp/pip-O1XzZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:08 INFO - Running setup.py (path:/tmp/pip-OdhbZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:08 INFO - Running setup.py (path:/tmp/pip-FefTAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:08 INFO - Running setup.py (path:/tmp/pip-IjUgU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:08 INFO - Running setup.py (path:/tmp/pip-FI24ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:08 INFO - Running setup.py (path:/tmp/pip-ZapH9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:08 INFO - Running setup.py (path:/tmp/pip-OyyUsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:08 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)) 11:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:08 INFO - Running setup.py (path:/tmp/pip-VJ3BWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:09 INFO - Running setup.py (path:/tmp/pip-OPbBrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:09 INFO - Running setup.py (path:/tmp/pip-u52_uT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:09 INFO - Running setup.py (path:/tmp/pip-65lxeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:09 INFO - Running setup.py (path:/tmp/pip-UOO2Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:09 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)) 11:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:09 INFO - Running setup.py (path:/tmp/pip-8E1fI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:09 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)) 11:29:09 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)) 11:29:09 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)) 11:29:09 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)) 11:29:09 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)) 11:29:09 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)) 11:29:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:29: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 11:29: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 11:29: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 11:29: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 11:29: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 11:29: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 11:29:11 INFO - Downloading blessings-1.6.tar.gz 11:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:29:11 INFO - Installing collected packages: blessings 11:29:11 INFO - Running setup.py install for blessings 11:29:11 INFO - Successfully installed blessings 11:29:11 INFO - Cleaning up... 11:29:11 INFO - Return code: 0 11:29:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:29:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:11 INFO - Reading from file tmpfile_stdout 11:29:11 INFO - Current package versions: 11:29:11 INFO - argparse == 1.2.1 11:29:11 INFO - blessings == 1.6 11:29:11 INFO - blobuploader == 1.2.4 11:29:11 INFO - docopt == 0.6.1 11:29:11 INFO - functools32 == 3.2.3-2 11:29:11 INFO - jsonschema == 2.5.1 11:29:11 INFO - manifestparser == 1.1 11:29:11 INFO - mozInstall == 1.12 11:29:11 INFO - mozcrash == 0.17 11:29:11 INFO - mozdebug == 0.1 11:29:11 INFO - mozdevice == 0.48 11:29:11 INFO - mozfile == 1.2 11:29:11 INFO - mozhttpd == 0.7 11:29:11 INFO - mozinfo == 0.9 11:29:11 INFO - mozleak == 0.1 11:29:11 INFO - mozlog == 3.2 11:29:11 INFO - moznetwork == 0.27 11:29:11 INFO - mozprocess == 0.22 11:29:11 INFO - mozprofile == 0.28 11:29:11 INFO - mozrunner == 6.11 11:29:11 INFO - mozscreenshot == 0.1 11:29:11 INFO - mozsystemmonitor == 0.3 11:29:11 INFO - moztest == 0.7 11:29:11 INFO - mozversion == 1.4 11:29:11 INFO - psutil == 3.1.1 11:29:11 INFO - requests == 1.2.3 11:29:11 INFO - wsgiref == 0.1.2 11:29:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:11 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')]} 11:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:11 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')]} 11:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:11 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:29:11 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 11:29:11 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 11:29:11 INFO - Using env: {'DISPLAY': ':0', 11:29:11 INFO - 'HOME': '/home/cltbld', 11:29:11 INFO - 'LANG': 'en_US.UTF-8', 11:29:11 INFO - 'LANGUAGE': 'en_US:en', 11:29:11 INFO - 'LOGNAME': 'cltbld', 11:29:11 INFO - 'MAIL': '/var/mail/cltbld', 11:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:11 INFO - 'MOZ_NO_REMOTE': '1', 11:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:11 INFO - 'NO_EM_RESTART': '1', 11:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:11 INFO - 'PWD': '/builds/slave/test', 11:29:11 INFO - 'SHELL': '/bin/bash', 11:29:11 INFO - 'SHLVL': '1', 11:29:11 INFO - 'TERM': 'linux', 11:29:11 INFO - 'TMOUT': '86400', 11:29:11 INFO - 'USER': 'cltbld', 11:29:11 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:11 INFO - '_': '/tools/buildbot/bin/python'} 11:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 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)) 11:29:11 INFO - Cleaning up... 11:29:11 INFO - Return code: 0 11:29:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:29:11 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')]} 11:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:11 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')]} 11:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:11 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:29:11 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 11:29:11 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 11:29:11 INFO - Using env: {'DISPLAY': ':0', 11:29:11 INFO - 'HOME': '/home/cltbld', 11:29:11 INFO - 'LANG': 'en_US.UTF-8', 11:29:11 INFO - 'LANGUAGE': 'en_US:en', 11:29:11 INFO - 'LOGNAME': 'cltbld', 11:29:11 INFO - 'MAIL': '/var/mail/cltbld', 11:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:11 INFO - 'MOZ_NO_REMOTE': '1', 11:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:11 INFO - 'NO_EM_RESTART': '1', 11:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:11 INFO - 'PWD': '/builds/slave/test', 11:29:11 INFO - 'SHELL': '/bin/bash', 11:29:11 INFO - 'SHLVL': '1', 11:29:11 INFO - 'TERM': 'linux', 11:29:11 INFO - 'TMOUT': '86400', 11:29:11 INFO - 'USER': 'cltbld', 11:29:11 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:11 INFO - '_': '/tools/buildbot/bin/python'} 11:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:29:12 INFO - Cleaning up... 11:29:12 INFO - Return code: 0 11:29:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:29:12 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')]} 11:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:12 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')]} 11:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:29:12 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 0x7fd0e663bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, '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': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', '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 11:29:12 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 11:29:12 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 11:29:12 INFO - Using env: {'DISPLAY': ':0', 11:29:12 INFO - 'HOME': '/home/cltbld', 11:29:12 INFO - 'LANG': 'en_US.UTF-8', 11:29:12 INFO - 'LANGUAGE': 'en_US:en', 11:29:12 INFO - 'LOGNAME': 'cltbld', 11:29:12 INFO - 'MAIL': '/var/mail/cltbld', 11:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:12 INFO - 'MOZ_NO_REMOTE': '1', 11:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:12 INFO - 'NO_EM_RESTART': '1', 11:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:12 INFO - 'PWD': '/builds/slave/test', 11:29:12 INFO - 'SHELL': '/bin/bash', 11:29:12 INFO - 'SHLVL': '1', 11:29:12 INFO - 'TERM': 'linux', 11:29:12 INFO - 'TMOUT': '86400', 11:29:12 INFO - 'USER': 'cltbld', 11:29:12 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:12 INFO - '_': '/tools/buildbot/bin/python'} 11:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:12 INFO - Downloading/unpacking flake8 11:29:12 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 11:29:12 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 11:29:12 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 11:29:12 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 11:29:12 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 11:29:12 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 11:29:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:29:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:29:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 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 11:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:29:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:29:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:29:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:29:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:29:15 INFO - no previously-included directories found matching 'docs/_build' 11:29:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:29:15 INFO - Running setup.py install for pep8 11:29:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:29:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:29:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:29:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:29:15 INFO - no previously-included directories found matching 'docs/_build' 11:29:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:29:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:29:15 INFO - Cleaning up... 11:29:15 INFO - Return code: 0 11:29:15 INFO - Running post-action listener: _resource_record_post_action 11:29:15 INFO - Running post-action listener: _start_resource_monitoring 11:29:15 INFO - Starting resource monitoring. 11:29:15 INFO - ##### 11:29:15 INFO - ##### Finished create-virtualenv step (success) 11:29:15 INFO - ##### 11:29:15 INFO - ##### 11:29:15 INFO - ##### Running install step. 11:29:15 INFO - ##### 11:29:15 INFO - Running pre-action listener: _resource_record_pre_action 11:29:15 INFO - Running main action method: install 11:29:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:15 INFO - Reading from file tmpfile_stdout 11:29:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:29:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:29:15 INFO - Reading from file tmpfile_stdout 11:29:15 INFO - Output received: 11:29:15 INFO - Usage: mozinstall [options] installer 11:29:15 INFO - Options: 11:29:15 INFO - -h, --help show this help message and exit 11:29:15 INFO - -d DEST, --destination=DEST 11:29:15 INFO - Directory to install application into. [default: 11:29:15 INFO - "/builds/slave/test"] 11:29:15 INFO - --app=APP Application being installed. [default: firefox] 11:29:15 INFO - mkdir: /builds/slave/test/build/application 11:29:15 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'] 11:29:15 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 11:29:31 INFO - Reading from file tmpfile_stdout 11:29:31 INFO - Output received: 11:29:31 INFO - /builds/slave/test/build/application/firefox/firefox 11:29:31 INFO - Running post-action listener: _resource_record_post_action 11:29:31 INFO - ##### 11:29:31 INFO - ##### Finished install step (success) 11:29:31 INFO - ##### 11:29:31 INFO - ##### 11:29:31 INFO - ##### Running run-tests step. 11:29:31 INFO - ##### 11:29:31 INFO - Running pre-action listener: _resource_record_pre_action 11:29:31 INFO - Running main action method: run_tests 11:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:29:31 INFO - Python 2.7.3 11:29:31 INFO - Return code: 0 11:29:31 INFO - grabbing minidump binary from tooltool 11:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:29:31 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 0x264f1c0>, '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 0x26500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2650550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:29:31 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 11:29:31 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 11:29:31 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 11:29:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:29:31 INFO - Return code: 0 11:29:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:29:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:29:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:29:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:29:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:29:32 INFO - Return code: 0 11:29:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 11:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 11:29:32 INFO - Using env: {'DISPLAY': ':0', 11:29:32 INFO - 'HOME': '/home/cltbld', 11:29:32 INFO - 'LANG': 'en_US.UTF-8', 11:29:32 INFO - 'LANGUAGE': 'en_US:en', 11:29:32 INFO - 'LOGNAME': 'cltbld', 11:29:32 INFO - 'MAIL': '/var/mail/cltbld', 11:29:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:32 INFO - 'MOZ_NO_REMOTE': '1', 11:29:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:29:32 INFO - 'NO_EM_RESTART': '1', 11:29:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:32 INFO - 'PWD': '/builds/slave/test', 11:29:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:32 INFO - 'SHELL': '/bin/bash', 11:29:32 INFO - 'SHLVL': '1', 11:29:32 INFO - 'TERM': 'linux', 11:29:32 INFO - 'TMOUT': '86400', 11:29:32 INFO - 'USER': 'cltbld', 11:29:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794', 11:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:32 INFO - '_': '/tools/buildbot/bin/python'} 11:29:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 11:29:32 INFO - mozversion application_buildid: 20160621101102 11:29:32 INFO - mozversion application_changeset: 51377a64158941f89ed73f388ae437cfa494c030 11:29:32 INFO - mozversion application_display_name: Nightly 11:29:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:29:32 INFO - mozversion application_name: Firefox 11:29:32 INFO - mozversion application_remotingname: firefox 11:29:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:29:32 INFO - mozversion application_vendor: Mozilla 11:29:32 INFO - mozversion application_version: 50.0a1 11:29:32 INFO - mozversion platform_buildid: 20160621101102 11:29:32 INFO - mozversion platform_changeset: 51377a64158941f89ed73f388ae437cfa494c030 11:29:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:29:32 INFO - mozversion platform_version: 50.0a1 11:29:32 INFO - using testdate: 1466533772 11:29:32 INFO - actual date: 1466533772 11:29:32 INFO - starting webserver on 'localhost:40232' 11:29:32 INFO - SUITE-START | Running 2 tests 11:29:32 INFO - TEST-START | dromaeo_css 11:29:32 INFO - operating with platform_type : linux_ 11:29:32 INFO - Initialising browser for dromaeo_css test... 11:29:33 INFO - TEST-INFO | started process 23132 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplJEPNH/profile http://localhost:40232/getInfo.html) 11:29:37 INFO - PROCESS | 23132 | __metrics Screen width/height:1600/1200 11:29:37 INFO - PROCESS | 23132 | colorDepth:24 11:29:37 INFO - PROCESS | 23132 | Browser inner width/height: 1024/697 11:29:37 INFO - PROCESS | 23132 | __metrics 11:29:38 INFO - PROCESS | 23132 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:29:39 INFO - TEST-INFO | 23132: exit 0 11:29:39 INFO - Browser initialized. 11:29:39 INFO - Running cycle 1/1 for dromaeo_css test... 11:29:39 INFO - TEST-INFO | started process 23216 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplJEPNH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:51 INFO - PROCESS | 23216 | RSS: Main: 174911488 11:29:51 INFO - PROCESS | 23216 | 11:30:42 INFO - PROCESS | 23216 | 1466533842413 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] 11:32:55 INFO - PROCESS | 23216 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40232/tests/dromaeo/cssquery-ext.html) 11:32:56 INFO - PROCESS | 23216 | RSS: Main: 264884224 11:32:56 INFO - PROCESS | 23216 | 11:35:58 INFO - PROCESS | 23216 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-ext.html (next: http://localhost:40232/tests/dromaeo/cssquery-jquery.html) 11:35:59 INFO - PROCESS | 23216 | RSS: Main: 257167360 11:35:59 INFO - PROCESS | 23216 | 11:39:01 INFO - PROCESS | 23216 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40232/tests/dromaeo/cssquery-mootools.html) 11:39:02 INFO - PROCESS | 23216 | RSS: Main: 266756096 11:39:02 INFO - PROCESS | 23216 | 11:42:04 INFO - PROCESS | 23216 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40232/tests/dromaeo/cssquery-prototype.html) 11:42:05 INFO - PROCESS | 23216 | RSS: Main: 260415488 11:42:05 INFO - PROCESS | 23216 | 11:45:08 INFO - PROCESS | 23216 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40232/tests/dromaeo/cssquery-yui.html) 11:45:08 INFO - PROCESS | 23216 | RSS: Main: 230240256 11:45:08 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | Cycle 1(1): loaded http://localhost:40232/tests/dromaeo/cssquery-yui.html (next: http://localhost:40232/tests/dromaeo/cssquery-dojo.html) 11:48:11 INFO - PROCESS | 23216 | RSS: Main: 379256832 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | __start_tp_report 11:48:11 INFO - PROCESS | 23216 | _x_x_mozilla_page_load 11:48:11 INFO - PROCESS | 23216 | _x_x_mozilla_page_load_details 11:48:11 INFO - PROCESS | 23216 | |i|pagename|runs| 11:48:11 INFO - PROCESS | 23216 | |0;dojo.html;2655.628212050313;2677.397585543252;2700.162949485659;2709.796765242606;2717.415755612543;4591.908161836761;4672.766361681919;4753.8811529711775;4875.683080599759;4930.580900623447;4779.402574678165;4803.3995750531185;4876.926846097312;4895.55939965403;4965.553100220979;4350.825966961318;4362.78186090695;4431.6233120184825;4495.730256184623;4613.838515651953;4515.4129963104915;4549.977250113745;4565.497795242521;4576;4588.7935042923145;4237.555056719031;4264.978675106636;4278.721883077621;4420.6242469390145;4430.933535996963;4362.47650281965;4367.519572846984;4374.78126093694;4443.333499974998;4443.822247110112;4399.38408622793;4400.053988392479;4402.273624851896;4406.779661016936;4440.7335559866515;4639.257718764981;4726.361941137937;4787.162246606865;4789.37738094049;4841.104395686774;5151.5621172200035;5208;5255.290535777659;5257.0274499217385;5262.421133675292;5222.268882356473;5225.346831646044;5254.579633629301;5279.788808447657;5281.894362112775;4687.6249900007915;4782.521747825259;4809.470958194595;4912.38595175603;4918.975405123023;5316.229146773696;5317.1226747586215;5344.812931547377;5353.625246232727;5366.221897824794;5085.389753229562;5086.491350864884;5129.076766182048;5139.152039913447;5142.04872098663;4932.975335123301;5034.697918124924;5071.46749591295;5074;5231.398389185217;5042.016806722727;5050.898982020339;5062.265971434122;5063.367079115111;5116.411612664591;5025.170846810309;5031.094403007421;5033.84898453047;5034.219695947134;5037.899242015213;4252.532221455637;4289.378040184219;4289.785510724451;4341.45731783527;4350.891227719332;3786.3184626767447;3814.7520411173186;3815.599362066943;3830.597787232356;3830.712696547714;4337;4353.651707863363;4356.716813407182;4379.364992076131;4398.780060996937;5177.585793136615;5193.246979187997;5200.8959820803375;5201.245819356172;5243.292155559027;4399.824007039747;4400.713953593006;4400.845970391021;4436.955630443719;4446.555344465528;4479.731216127043;4599.563041511051;4620.237660786;4646.349511068385;4651.139539185261;4833.10587541306;4838.056578967067;4847.321375007502;4851.46633870281;4886.071646387317;5112.948870511396;5124.052050370693;5155;5172.9741351293005;5226.085435048927;4707.388039554836;4738.170820106537;4764.30917516965;4785.138675038526;4805.1590971579135;5034.84895453137;5048;5049.545540901337;5053.090443720165;5108.7445627717725;4990.925136122888;5110.66780659256;5121.871953201051;5133;5169.844904652867;4969.503049695001;5018.272350509083;5035.723035232951;5036.093503169464;5063.797448102035;5041.0422019816115;5046.495350465071;5048.873778155576;5051.090803655377;5097.362829646294;4698.812047518198;4880.438749543848;4971.825986090469;4986.301917731593;5037.420696619935;5041.722705251246;5096.51583099605;5096.796128154832;5119.6416250862085;5145.202493613496;4976.502349765139;4992.675476094042;4997.950020499894;5000.1749711297225;5051.494850515066 11:48:11 INFO - PROCESS | 23216 | |1;ext.html;558.2645039307394;567.1662655895833;602.0908428273311;606.690587800222;617.817743765589;28670;31847.203819904484;31867.52198717021;39089;40574;40574.59425405745;41138.38292425616;41191.17617647645;41234.58765412345;41262.58737412625;7707.190744971781;7867.6852925882895;7868.645910934022;7871.960640196791;7872.252136047066;7638.961805190994;7739.729109481141;7741.5355078695175;7747;7747.96126019372;7716.961415192916;7754.8449031019345;7780.066392032964;7780.5720685362285;7785.766427007199;7609.581473018981;7651.273129052786;7695.653695583712;7711.383089352895;7718.575478348688;7693;7776.65005074773;7776.844463110762;7782.066152061732;7787.06555213378;38240;38578.22843543142;38585.80707096474;38627;38644.61355386438;3165.271987442901;3239.82180980046;3249.5938007749032;3373.595168579762;3538.6815186633326;29432.70567294321;29726.40547189044;29905.102846914626;30074.84962575195;30332.54501182484;3477.634848340913;3570.250247448039;3575.803330816804;3586.5337506124038;3594.4428613564937;250366;250858;251291;251320;251568;30225;51345.486545134816;51454;51807.48192518102;51837;31414.214644634067;33546.832265839;33623.83188084093;33713.831430842685;33753;13630.114042587398;13905.86094139066;13915.30423478822;13925.373358198905;13929.373178207004;11073.114150867912;11210.495527701272;11215.327080375204;11219.775604488028;11221.71945701364;7819.921800782033;7840.607969601497;7900.301435191346;7955.124936257007;7966.880496792553;3085.953710694341;3098.690130986883;3130.1235654016914;3138;3152.9684703152675;929.3448119076036;951.7382719752123;968.6658102954469;971.5336638413601;973.5862258540234;26431.867840660674;27474.17577472679;27958.301042473693;28060.85969570139;28187.859060704566;866.7486428935664;918.0773322810533;977.8900947424725;1009.6062535611118;1024.3393011507535;14223.85776142246;14509.889934598912;14580.781288280685;14712.33794479251;14834.777478337834;392604;393364;397853;399079;401975;46430.30354544685;47053;47101.764491177324;47122.29316560255;47155;1081.1404933078222;1173.202222488699;1189.5301355964345;1192.469351138735;1204.0728638948135;27001.729982699922;28726.281842954093;28875.5668664966;29241.122766317043;29320.853395732884;41247.58752412558;41519.58480415278;41966.79016604897;41983.79008105062;42079.7896010518;307590;309589;309968;310480;310566;21878.23426180076;23456.41358965971;24230.75769242285;24384.878075609508;24820.37949051288;40146.59853401545;40352.596474034886;40443.19113617652;41207.58792412037;42193.156136876474;34147.31705365829;36519.63480365162;37076.44385334276;37191.070223244635;37214.255714886094;36090.278194435436;36864.44703329506;37071.44392834056;37122;37244.627553724116;36614.450783238804;36761.77239617478;37105.443418349285;37223.62776372202;37375;31731;33278.334433310716;33359.83320083384;33388.66611333856;33663;25313.493730124927;25334.239972800846;25644.61533076968;25649.615255770805;26071.73928260769 11:48:11 INFO - PROCESS | 23216 | |2;jquery.html;369.59160128058494;371.41131306878594;379.8670465337134;386.4936932618272;388.1635076410335;15613.765793513092;16838.326466941322;17976.280948762065;17980.82019179811;17985.91007044963;11229.663110106689;15718.842811571909;16583;16590.75113873293;17253.396131135414;4621.537846215377;4790.760461976887;4827.10698520773;4832.492588278233;4834.1540230459705;3469.930601387971;5260.631755777097;5575.944240557604;5586.748596313156;5669.6314739541795;5436.619436639437;5610.887782244353;5619.438056194368;5628.0713682242385;5639.605227634067;5611.410801865786;5651.378348381675;5654.180143879114;5687.118496633028;5695.515881150107;3351.262722201112;5081.8475445736685;5384.111621582434;5397.163439666819;5482;12590.181638193488;12594.74810503794;12604.11771176018;18462.44612661622;19626.607467850707;1906.5996140810446;2078.6362386582437;2084.405944305881;2172.652375619893;2404.9398765031015;9182.08179182083;9198.494082825442;9738.90261097387;10157.339772914736;13747.725045499034;1740.3995621510558;1770.672425601255;1779.8932064076198;1845.1051240148518;1893.943181704551;61956;69121.65439172823;69231;70159;71569.28430715678;10174.898251017543;10294.794104118026;10377.9481102594;10429.165666746641;10549.525271362654;14184.64538386528;14230.86153107749;14266.215358155194;14276.42959274479;14279;7348.375388091965;7740.109887362913;7745.225477452323;7771.145174030852;7874.291313781787;7342.77971660851;7375.631218439056;7556.4710470266555;7580.431467639949;7589.658465369072;4422.823087076546;4467.508574056851;4528.773561321921;4540;4622.791974361162;1722.7071397862394;1760.9383671571434;1876.7278744581959;1902.4958386027713;1935.458071739915;772.3049255669943;841.5329492131925;865.2731705367521;881.1452890696015;978.1343510992823;16287.104209268367;16622.75065874013;16678.58303542421;16822.57943551421;17382.3047078118;828.8093738440192;838.8406202821567;871.7776966873533;892.3485855325519;910.7855401670299;9434.339596228197;9529.285303602257;9648.71053868385;9667.758306042264;9877.160441362565;381938;388219;391489;391632;391761;75375;77542;80076.59961700154;82472.58763706143;83268;832.3881946769241;876.9123087691307;892.1168043636753;902.9909700902907;905.2848249882519;15129.167895765839;15286.770698439756;15410.537683869501;15455.768163477333;15581.532554023397;36921.63078369182;37120.77275363487;37236;37466.625333747405;37575.624243758306;50917;52120.218196726324;53994.19008714794;53997;54039;7778.338841198335;7798;7892.486988345969;8066.314363278952;8071.4753541019645;9015.549222538977;10980.890191097988;11977.520899163936;12052.276863388224;12407.44166512491;9533.47565883883;9862.85205721929;10312.690619281433;10824.891751082389;10874.130069594255;10636.361818290618;10683.30558513694;10724.73188170302;10854.565817367216;11233.101351891992;10114.544845481823;10359.94820025895;10524.684259472227;10574.10120139766;11248.718782030515;7691.961540192263;7970.2826745593175;7978.165022044846;8059.43583949118;8099.230573095547;7765.805854853448;9908.702738917844;10082.193424526167;10371.481425928825;10413.9479302603 11:48:11 INFO - PROCESS | 23216 | |3;mootools.html;1233.3339996401944;1365.5698454986675;1399.1674953402726;1454.7526920423527;1703.3527259641342;6867.210270818858;7597.202293759152;7872.685092596289;7879.527228366302;8151.225633564816;7849.489783164105;7949.483283586563;7981.6807327706965;8025;8052.114267430578;1899.4681489183051;2196.242296407737;2222.355516900097;2224.733032036158;2228.665700144983;2167.3172950520534;2233.6761169630395;2235.072444935358;2250.17868478006;2252.538229662923;2132.936011919645;2157.2126173946476;2158.2985529702833;2162.8810415427147;2167.1656412281304;1850.9167087480962;2094.3821572636107;2185.0604240176717;2195.923142690014;2196.5826492966444;2164.3074216250807;2211.137656314039;2218.279059305716;2237.5860465813876;2240.596692595332;7929.5242285463055;7941.523508589503;7966.760997170094;8003.15966823481;8012.3990700697095;4554.590086892195;4676.859694209179;4679.578837904571;4684.859454216379;4687.765611719435;2977.077106097117;3033.6662967073603;3072;3082.414341275173;3092.6443459002276;1017.5522769981185;1054.3726482742832;1075.1345167140448;1078.7734575739105;1085.5006696919427;10762.838557421723;10785.352878827218;10841.078508326802;10858.45707714619;10879.945600272027;4129.525104613008;4241.1941731071;4295.269804133305;4367.5414081520985;4371.475422949203;9086.681966131153;9170.082991700776;9178.86231706525;9181.265498760084;9189.81620367589;4676;4722.929156062662;4752.5485078917445;4758.3100450434495;4758.928616070762;3713.7400381973002;4159.9376009359885;4192.727472714325;4252.4046633471335;4304.741715497017;3662.3957047087465;3681.2269423420844;3704.5739739930254;3759.285735710206;3767.3407153747985;1165.9825102623468;1199.698327314873;1215.6231568213882;1221.1818081885158;1224.13086708438;841.8905542279465;872.289084396215;885.57049830832;891.0465801592234;911.293747345799;5613;5639.971800141055;5655.5475561954945;5739.8565035874435;5944.910826337608;852.957352132391;862.2024627219797;863.2748491267365;872.1322284327008;924.713338864954;3870.2259548090497;3984.940225896614;4001.419794129835;4023.7183397161916;4052.5339585947995;9491.477968711788;9715;9874.913759486451;9888.851667224997;9926.553305101288;9792.314537982415;9792.657256996114;9819.410835349901;9834.901650983542;9845.655402060893;7009.7897063088185;7283.543291341753;7325.304096110967;7419.07261592301;7436.442266830009;5687.857803554944;5723.141528770718;5815.76736930518;5823.679697616672;5903.734331954986;8307.792305192419;8975.50634715097;9097.272218222659;9103.772405689911;9231.307651925998;8885.91114088851;9046.411983221069;9119.361644684808;9146.908530914605;9173;6214.316425193316;6396.552241343247;6404.103425520338;6870.656467176521;7047.048648432396;6801.353871382211;6948.617826019416;7662.885056724265;7694.307512323918;7695.345895598713;6763.2898545652;6811.148606424197;6940.514164008471;7029.648517574203;7036.648167591702;6758.324167583202;6772.762953296611;6784.423324017514;6883.586984780929;6917.9308206917285;6547.181602299713;6607.537472376887;6678.699458524281;6682.63245521501;6687.164104486939;4742.52574742523;4830.95169048305;4851.417829860439;4854.247591623304;4898.191798353232;4927.852164435073;4956.900861982812;5025.47232540578;5047.015831912742;5055.69665820052 11:48:11 INFO - PROCESS | 23216 | |4;prototype.html;301.92300963254365;305.38922155688624;307.88300445830583;308.56954548404974;312.20699423464396;4781.976090119549;4880.170371036923;4892.951070489298;4896.094222568827;4896.804127834891;4761.2143996240575;4802.279658051285;4811.10994466024;4817.796165549914;4820.421549414075;3810.637989391017;3815.4276858471244;3828.0812604974744;3848.9992601923423;3863.80680965952;3687.15195505034;3823.21624067067;3860.768353898775;3862.555806082308;3868.9613103869024;3689.6863766579872;3778.6599206071446;3810.627594578804;3810.8285127169206;3831.67430768385;3776.678982286509;3777.263433630443;3788.488554045196;3792.4880141181;3804.8097595120134;3174.6190357232076;3261.4781634938295;3268.4280250956226;3277.475603903364;3305.0004747128123;4304.225239456897;4533.886652833673;4687.414073240845;4719.197736384788;4726.409198850144;4314.697971142022;4315.2738904438165;4326.875012496742;4340.8480703175555;4340.872972243609;4682.953170468286;5058.494150584949;5068.138416469209;5075.046107248699;5079.415867175285;3514.859405623772;3640.5631324241263;3644.1982763791925;3651.178484840916;3652.7077833773233;4999.400071991348;5052.343195384577;5135.922961155586;5213.478652134794;5224.973875130676;3676.6690997810324;3677.9264414711556;3678.3562876496508;3684.0421490412687;3693.796841173761;3744.1388480649603;3752.7373083883867;3754.249150169977;3765.1904840459433;3771.6228377162065;4126.514454796271;4184;4245.97877010613;4260;4265.722728022669;5007.574356179765;5010.824621138316;5012.649114561946;5012.724300163453;5015.22264049073;1280.327827890365;1314.3953781260727;1318.3803612302202;1328.860469650692;1332.4403750424844;822.9547374894438;854.9358798090168;863.9049704532497;864.2481041493941;865.5628907401722;3940.3402703175243;3943.6056394360335;3967.028078120821;3973.7417067890497;3978.8660231833787;4518.367428560005;4542.523035081335;4559.4072770539615;4569.977150114228;4583.977080114645;3939.428782826474;3942.6397892726964;4017.216642754635;4020.4974378202724;4043.9393409098298;2481.8634975076184;2524.7475252474605;2745.6018877262686;2757.352022274764;2761.8895244190007;4778.426588809296;4791.784369703371;4821.590164835958;4828.179209534387;4844.685095468783;5242.213667949838;5247.842564723064;5249.606279529051;5260.473952604709;5288.735563221823;4632.305154226893;4830.99999999993;4854.495106516991;4857;4902.607791376681;5172.767225474788;5178.067947769287;5183.486009285418;5191.584673226057;5219.121116398018;4912.354361289008;5115.232715092617;5115.92326115116;5122;5162.896742065062;4676.906461870675;4770.952290477189;4804.591609713214;4811.374521312207;4943.406791184937;3526.4181410067054;3579.087332730137;3659.5425571803526;3683.134463401045;3711.3690672586267;5116.692998420107;5130.8973820524125;5136.332276803992;5160.200168973815;5176.327077479904;4895.853124406273;4926.078829256521;4997.775100120576;5008.198688209869;5008.724520151281;4941.456439791692;4966.428860680923;4969.602431805519;4986.950130498649;4988.201887697951;4899.289603007615;4904.166291730486;4919.409670839522;4924.581410580141;4931.235658472942;4867.756612169448;4998.225275082368;5018.247262910447;5020.569137795711;5022.824201152942;4882.780274887567;4885.853424397274;4906.852794416173;4914.950850491449;4927.507249275044 11:48:11 INFO - PROCESS | 23216 | |5;yui.html;638.3170008091338;648.2545073165861;685.8765422224001;722.3246264742464;731.2358585278383;12549.623511294665;13538.864611353883;13549.051566390357;13553.52562660306;13564.05051646385;12287.38563071845;12402.069844761656;12439.751204975895;12453.190542614724;12481.126321157522;626.3235705438116;767.87330090535;771.255738212626;783.165928286376;796.0646240667193;791.0467886197148;799.2207597592359;799.7121036426911;802.5666140284239;802.7070119406404;824.1634740738156;830.6511265268601;831.2477208126655;835.8119423129808;838.5010918503481;648.1929997153527;803.9990212185808;825.5459497276474;829.3323874281201;839.17760594617;613.4111253196936;613.5582380685898;613.6808859393135;613.9416755408229;613.9907901381483;13557.118787278796;13603;13726.931365343109;13744.312784360742;13748.518801841887;12727.236365817987;13326.466941322335;13473.528426505025;13490;13492;11515.490481992665;11613.76772464546;11620.477078531487;11767.23512971654;11798.882011179865;3282;3307.8842240521467;3398.9660103398896;4237.4915010198665;4337.349397590387;59420.70289648567;60142.699286503725;60173.39826601722;60373.69813150906;60746.696266518826;10271.9486402569;10291.612335946329;10527.157827373792;10668.573257069786;10670.199735019905;17839.643207135785;17846.732299015526;17868.642627147383;17874.46376608704;17885.105744712713;6655.434288085567;6769.932300677028;6793.694283757242;6865.038894554767;6970.512064155562;3709.0356507308293;3784.7161462890394;3818.59904710007;3824.388097904322;3855.0747820522874;2925.5611658251005;3014.427258820817;3015.517517197238;3042.4371491274187;3053.2977415194187;523.2334629767333;532.6484520216638;535.3361831329202;536.1368197202501;540.835045311176;553.4299671338447;583.821934310031;589.0957380421033;590.3152343281772;600.4115966352998;7968.840623187504;8060.1133875274845;8104.473209241381;8382.455140415854;8426.19218787452;455.5672410269683;475.60049558371134;566.1309889319874;591.3347484080409;593.1073734030257;3754.4180651998986;3813.3517302058717;3849.9037524062123;3973.940390894139;4018.2365350583295;54654.72672636611;54753;55390.72304638451;55390.72304638532;55669;46720.5327946723;47473.28790068152;47790;80155.59922200351;82938;43962.78018609886;57005;69156.6542167286;69205.30794692089;73476.63261683764;8469.153084691729;8525.91474085276;8741.945642773815;8798.38411311202;8851.911480885108;33655.32689346249;39276;39297.410538841366;39633.60366396299;40660;41818;60224.39775602363;61388;62975.6851215741;64369.35630643821;10928.50821713009;11268.15488838324;11290.266132701348;11459.828102578635;11601.82597261025;12063.276203427822;12159.088068394729;12191.329476878856;13115.868841311465;13161.802572961222;10240;12424.192427492546;12480.126391152895;12660.739799968293;12722.363881806057;12000.699982500508;12060.03519718438;12158.088143389105;12168.08739344535;12465.439055242354;11415.486303116546;11772.17594768358;11785.410729463321;11788.4695188715;11789.292642441482;9382.343235973416;9549.9045009549;9580.377275477073;9834.852477212704;9840.507974601385;9159.633614655339;9330.906690933003;9493.905060949302;9661.613535458502;9806.509674516388 11:48:11 INFO - PROCESS | 23216 | __end_tp_report 11:48:11 INFO - PROCESS | 23216 | __start_cc_report 11:48:11 INFO - PROCESS | 23216 | _x_x_mozilla_cycle_collect,923 11:48:11 INFO - PROCESS | 23216 | __end_cc_report 11:48:11 INFO - PROCESS | 23216 | __startTimestamp1466534891695__endTimestamp 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | ------- Summary: start ------- 11:48:11 INFO - PROCESS | 23216 | Number of tests: 6 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | [#0] dojo.html Cycles:165 Average:4754.18 Median:4895.56 stddev:508.43 (10.4%) stddev-sans-first:482.60 11:48:11 INFO - PROCESS | 23216 | Values: 2655.6 2677.4 2700.2 2709.8 2717.4 4591.9 4672.8 4753.9 4875.7 4930.6 4779.4 4803.4 4876.9 4895.6 4965.6 4350.8 4362.8 4431.6 4495.7 4613.8 4515.4 4550.0 4565.5 4576.0 4588.8 4237.6 4265.0 4278.7 4420.6 4430.9 4362.5 4367.5 4374.8 4443.3 4443.8 4399.4 4400.1 4402.3 4406.8 4440.7 4639.3 4726.4 4787.2 4789.4 4841.1 5151.6 5208.0 5255.3 5257.0 5262.4 5222.3 5225.3 5254.6 5279.8 5281.9 4687.6 4782.5 4809.5 4912.4 4919.0 5316.2 5317.1 5344.8 5353.6 5366.2 5085.4 5086.5 5129.1 5139.2 5142.0 4933.0 5034.7 5071.5 5074.0 5231.4 5042.0 5050.9 5062.3 5063.4 5116.4 5025.2 5031.1 5033.8 5034.2 5037.9 4252.5 4289.4 4289.8 4341.5 4350.9 3786.3 3814.8 3815.6 3830.6 3830.7 4337.0 4353.7 4356.7 4379.4 4398.8 5177.6 5193.2 5200.9 5201.2 5243.3 4399.8 4400.7 4400.8 4437.0 4446.6 4479.7 4599.6 4620.2 4646.3 4651.1 4833.1 4838.1 4847.3 4851.5 4886.1 5112.9 5124.1 5155.0 5173.0 5226.1 4707.4 4738.2 4764.3 4785.1 4805.2 5034.8 5048.0 5049.5 5053.1 5108.7 4990.9 5110.7 5121.9 5133.0 5169.8 4969.5 5018.3 5035.7 5036.1 5063.8 5041.0 5046.5 5048.9 5051.1 5097.4 4698.8 4880.4 4971.8 4986.3 5037.4 5041.7 5096.5 5096.8 5119.6 5145.2 4976.5 4992.7 4998.0 5000.2 5051.5 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | [#1] ext.html Cycles:180 Average:46131.51 Median:36813.11 stddev:85728.41 (232.9%) stddev-sans-first:85900.62 11:48:11 INFO - PROCESS | 23216 | Values: 558.3 567.2 602.1 606.7 617.8 28670.0 31847.2 31867.5 39089.0 40574.0 40574.6 41138.4 41191.2 41234.6 41262.6 7707.2 7867.7 7868.6 7872.0 7872.3 7639.0 7739.7 7741.5 7747.0 7748.0 7717.0 7754.8 7780.1 7780.6 7785.8 7609.6 7651.3 7695.7 7711.4 7718.6 7693.0 7776.7 7776.8 7782.1 7787.1 38240.0 38578.2 38585.8 38627.0 38644.6 3165.3 3239.8 3249.6 3373.6 3538.7 29432.7 29726.4 29905.1 30074.8 30332.5 3477.6 3570.3 3575.8 3586.5 3594.4 250366.0 250858.0 251291.0 251320.0 251568.0 30225.0 51345.5 51454.0 51807.5 51837.0 31414.2 33546.8 33623.8 33713.8 33753.0 13630.1 13905.9 13915.3 13925.4 13929.4 11073.1 11210.5 11215.3 11219.8 11221.7 7819.9 7840.6 7900.3 7955.1 7966.9 3086.0 3098.7 3130.1 3138.0 3153.0 929.3 951.7 968.7 971.5 973.6 26431.9 27474.2 27958.3 28060.9 28187.9 866.7 918.1 977.9 1009.6 1024.3 14223.9 14509.9 14580.8 14712.3 14834.8 392604.0 393364.0 397853.0 399079.0 401975.0 46430.3 47053.0 47101.8 47122.3 47155.0 1081.1 1173.2 1189.5 1192.5 1204.1 27001.7 28726.3 28875.6 29241.1 29320.9 41247.6 41519.6 41966.8 41983.8 42079.8 307590.0 309589.0 309968.0 310480.0 310566.0 21878.2 23456.4 24230.8 24384.9 24820.4 40146.6 40352.6 40443.2 41207.6 42193.2 34147.3 36519.6 37076.4 37191.1 37214.3 36090.3 36864.4 37071.4 37122.0 37244.6 36614.5 36761.8 37105.4 37223.6 37375.0 31731.0 33278.3 33359.8 33388.7 33663.0 25313.5 25334.2 25644.6 25649.6 26071.7 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | [#2] jquery.html Cycles:180 Average:24312.56 Median:4498.14 stddev:64320.70 (1429.9%) stddev-sans-first:64476.01 11:48:11 INFO - PROCESS | 23216 | Values: 369.6 371.4 379.9 386.5 388.2 15613.8 16838.3 17976.3 17980.8 17985.9 11229.7 15718.8 16583.0 16590.8 17253.4 4621.5 4790.8 4827.1 4832.5 4834.2 3469.9 5260.6 5575.9 5586.7 5669.6 5436.6 5610.9 5619.4 5628.1 5639.6 5611.4 5651.4 5654.2 5687.1 5695.5 3351.3 5081.8 5384.1 5397.2 5482.0 12590.2 12594.7 12604.1 18462.4 19626.6 1906.6 2078.6 2084.4 2172.7 2404.9 9182.1 9198.5 9738.9 10157.3 13747.7 1740.4 1770.7 1779.9 1845.1 1893.9 61956.0 69121.7 69231.0 70159.0 71569.3 10174.9 10294.8 10377.9 10429.2 10549.5 14184.6 14230.9 14266.2 14276.4 14279.0 7348.4 7740.1 7745.2 7771.1 7874.3 7342.8 7375.6 7556.5 7580.4 7589.7 4422.8 4467.5 4528.8 4540.0 4622.8 1722.7 1760.9 1876.7 1902.5 1935.5 772.3 841.5 865.3 881.1 978.1 16287.1 16622.8 16678.6 16822.6 17382.3 828.8 838.8 871.8 892.3 910.8 9434.3 9529.3 9648.7 9667.8 9877.2 381938.0 388219.0 391489.0 391632.0 391761.0 75375.0 77542.0 80076.6 82472.6 83268.0 832.4 876.9 892.1 903.0 905.3 15129.2 15286.8 15410.5 15455.8 15581.5 36921.6 37120.8 37236.0 37466.6 37575.6 50917.0 52120.2 53994.2 53997.0 54039.0 7778.3 7798.0 7892.5 8066.3 8071.5 9015.5 10980.9 11977.5 12052.3 12407.4 9533.5 9862.9 10312.7 10824.9 10874.1 10636.4 10683.3 10724.7 10854.6 11233.1 10114.5 10359.9 10524.7 10574.1 11248.7 7692.0 7970.3 7978.2 8059.4 8099.2 7765.8 9908.7 10082.2 10371.5 10413.9 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | [#3] mootools.html Cycles:180 Average:5251.29 Median:5626.49 stddev:2902.43 (51.6%) stddev-sans-first:2894.86 11:48:11 INFO - PROCESS | 23216 | Values: 1233.3 1365.6 1399.2 1454.8 1703.4 6867.2 7597.2 7872.7 7879.5 8151.2 7849.5 7949.5 7981.7 8025.0 8052.1 1899.5 2196.2 2222.4 2224.7 2228.7 2167.3 2233.7 2235.1 2250.2 2252.5 2132.9 2157.2 2158.3 2162.9 2167.2 1850.9 2094.4 2185.1 2195.9 2196.6 2164.3 2211.1 2218.3 2237.6 2240.6 7929.5 7941.5 7966.8 8003.2 8012.4 4554.6 4676.9 4679.6 4684.9 4687.8 2977.1 3033.7 3072.0 3082.4 3092.6 1017.6 1054.4 1075.1 1078.8 1085.5 10762.8 10785.4 10841.1 10858.5 10879.9 4129.5 4241.2 4295.3 4367.5 4371.5 9086.7 9170.1 9178.9 9181.3 9189.8 4676.0 4722.9 4752.5 4758.3 4758.9 3713.7 4159.9 4192.7 4252.4 4304.7 3662.4 3681.2 3704.6 3759.3 3767.3 1166.0 1199.7 1215.6 1221.2 1224.1 841.9 872.3 885.6 891.0 911.3 5613.0 5640.0 5655.5 5739.9 5944.9 853.0 862.2 863.3 872.1 924.7 3870.2 3984.9 4001.4 4023.7 4052.5 9491.5 9715.0 9874.9 9888.9 9926.6 9792.3 9792.7 9819.4 9834.9 9845.7 7009.8 7283.5 7325.3 7419.1 7436.4 5687.9 5723.1 5815.8 5823.7 5903.7 8307.8 8975.5 9097.3 9103.8 9231.3 8885.9 9046.4 9119.4 9146.9 9173.0 6214.3 6396.6 6404.1 6870.7 7047.0 6801.4 6948.6 7662.9 7694.3 7695.3 6763.3 6811.1 6940.5 7029.6 7036.6 6758.3 6772.8 6784.4 6883.6 6917.9 6547.2 6607.5 6678.7 6682.6 6687.2 4742.5 4831.0 4851.4 4854.2 4898.2 4927.9 4956.9 5025.5 5047.0 5055.7 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | [#4] prototype.html Cycles:180 Average:4127.90 Median:4703.31 stddev:1189.87 (25.3%) stddev-sans-first:1158.04 11:48:11 INFO - PROCESS | 23216 | Values: 301.9 305.4 307.9 308.6 312.2 4782.0 4880.2 4893.0 4896.1 4896.8 4761.2 4802.3 4811.1 4817.8 4820.4 3810.6 3815.4 3828.1 3849.0 3863.8 3687.2 3823.2 3860.8 3862.6 3869.0 3689.7 3778.7 3810.6 3810.8 3831.7 3776.7 3777.3 3788.5 3792.5 3804.8 3174.6 3261.5 3268.4 3277.5 3305.0 4304.2 4533.9 4687.4 4719.2 4726.4 4314.7 4315.3 4326.9 4340.8 4340.9 4683.0 5058.5 5068.1 5075.0 5079.4 3514.9 3640.6 3644.2 3651.2 3652.7 4999.4 5052.3 5135.9 5213.5 5225.0 3676.7 3677.9 3678.4 3684.0 3693.8 3744.1 3752.7 3754.2 3765.2 3771.6 4126.5 4184.0 4246.0 4260.0 4265.7 5007.6 5010.8 5012.6 5012.7 5015.2 1280.3 1314.4 1318.4 1328.9 1332.4 823.0 854.9 863.9 864.2 865.6 3940.3 3943.6 3967.0 3973.7 3978.9 4518.4 4542.5 4559.4 4570.0 4584.0 3939.4 3942.6 4017.2 4020.5 4043.9 2481.9 2524.7 2745.6 2757.4 2761.9 4778.4 4791.8 4821.6 4828.2 4844.7 5242.2 5247.8 5249.6 5260.5 5288.7 4632.3 4831.0 4854.5 4857.0 4902.6 5172.8 5178.1 5183.5 5191.6 5219.1 4912.4 5115.2 5115.9 5122.0 5162.9 4676.9 4771.0 4804.6 4811.4 4943.4 3526.4 3579.1 3659.5 3683.1 3711.4 5116.7 5130.9 5136.3 5160.2 5176.3 4895.9 4926.1 4997.8 5008.2 5008.7 4941.5 4966.4 4969.6 4987.0 4988.2 4899.3 4904.2 4919.4 4924.6 4931.2 4867.8 4998.2 5018.2 5020.6 5022.8 4882.8 4885.9 4906.9 4915.0 4927.5 11:48:11 INFO - PROCESS | 23216 | 11:48:11 INFO - PROCESS | 23216 | [#5] yui.html Cycles:180 Average:15319.36 Median:24132.80 stddev:19490.47 (80.8%) stddev-sans-first:19513.97 11:48:11 INFO - PROCESS | 23216 | Values: 638.3 648.3 685.9 722.3 731.2 12549.6 13538.9 13549.1 13553.5 13564.1 12287.4 12402.1 12439.8 12453.2 12481.1 626.3 767.9 771.3 783.2 796.1 791.0 799.2 799.7 802.6 802.7 824.2 830.7 831.2 835.8 838.5 648.2 804.0 825.5 829.3 839.2 613.4 613.6 613.7 613.9 614.0 13557.1 13603.0 13726.9 13744.3 13748.5 12727.2 13326.5 13473.5 13490.0 13492.0 11515.5 11613.8 11620.5 11767.2 11798.9 3282.0 3307.9 3399.0 4237.5 4337.3 59420.7 60142.7 60173.4 60373.7 60746.7 10271.9 10291.6 10527.2 10668.6 10670.2 17839.6 17846.7 17868.6 17874.5 17885.1 6655.4 6769.9 6793.7 6865.0 6970.5 3709.0 3784.7 3818.6 3824.4 3855.1 2925.6 3014.4 3015.5 3042.4 3053.3 523.2 532.6 535.3 536.1 540.8 553.4 583.8 589.1 590.3 600.4 7968.8 8060.1 8104.5 8382.5 8426.2 455.6 475.6 566.1 591.3 593.1 3754.4 3813.4 3849.9 3973.9 4018.2 54654.7 54753.0 55390.7 55390.7 55669.0 46720.5 47473.3 47790.0 80155.6 82938.0 43962.8 57005.0 69156.7 69205.3 73476.6 8469.2 8525.9 8741.9 8798.4 8851.9 33655.3 39276.0 39297.4 39633.6 40660.0 41818.0 60224.4 61388.0 62975.7 64369.4 10928.5 11268.2 11290.3 11459.8 11601.8 12063.3 12159.1 12191.3 13115.9 13161.8 10240.0 12424.2 12480.1 12660.7 12722.4 12000.7 12060.0 12158.1 12168.1 12465.4 11415.5 11772.2 11785.4 11788.5 11789.3 9382.3 9549.9 9580.4 9834.9 9840.5 9159.6 9330.9 9493.9 9661.6 9806.5 11:48:11 INFO - PROCESS | 23216 | -------- Summary: end -------- 11:48:11 INFO - PROCESS | 23216 | 11:48:12 INFO - TEST-INFO | 23216: exit 0 11:48:12 INFO - TEST-OK | dromaeo_css | took 1119579ms 11:48:12 INFO - TEST-START | kraken 11:48:12 INFO - operating with platform_type : linux_ 11:48:12 INFO - Initialising browser for kraken test... 11:48:12 INFO - TEST-INFO | started process 23399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvyevEN/profile http://localhost:40232/getInfo.html) 11:48:16 INFO - PROCESS | 23399 | __metrics Screen width/height:1600/1200 11:48:16 INFO - PROCESS | 23399 | colorDepth:24 11:48:16 INFO - PROCESS | 23399 | Browser inner width/height: 1024/697 11:48:16 INFO - PROCESS | 23399 | __metrics 11:48:17 INFO - PROCESS | 23399 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:48:18 INFO - TEST-INFO | 23399: exit 0 11:48:18 INFO - Browser initialized. 11:48:18 INFO - Running cycle 1/1 for kraken test... 11:48:18 INFO - TEST-INFO | started process 23478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvyevEN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:30 INFO - PROCESS | 23478 | RSS: Main: 176099328 11:48:30 INFO - PROCESS | 23478 | 11:48:33 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-beat-detection) 11:48:34 INFO - PROCESS | 23478 | RSS: Main: 233054208 11:48:34 INFO - PROCESS | 23478 | 11:48:37 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-dft) 11:48:37 INFO - PROCESS | 23478 | RSS: Main: 220848128 11:48:37 INFO - PROCESS | 23478 | 11:48:41 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-fft) 11:48:41 INFO - PROCESS | 23478 | RSS: Main: 288509952 11:48:41 INFO - PROCESS | 23478 | 11:48:43 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40232/tests/kraken/driver.html?testName=audio-oscillator) 11:48:44 INFO - PROCESS | 23478 | RSS: Main: 199163904 11:48:44 INFO - PROCESS | 23478 | 11:48:46 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:47 INFO - PROCESS | 23478 | RSS: Main: 202788864 11:48:47 INFO - PROCESS | 23478 | 11:48:50 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40232/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:51 INFO - PROCESS | 23478 | RSS: Main: 239177728 11:48:51 INFO - PROCESS | 23478 | 11:48:54 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40232/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:55 INFO - PROCESS | 23478 | RSS: Main: 234827776 11:48:55 INFO - PROCESS | 23478 | 11:48:58 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40232/tests/kraken/driver.html?testName=json-parse-financial) 11:48:58 INFO - PROCESS | 23478 | RSS: Main: 237395968 11:48:58 INFO - PROCESS | 23478 | 11:49:00 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:49:01 INFO - PROCESS | 23478 | RSS: Main: 226385920 11:49:01 INFO - PROCESS | 23478 | 11:49:02 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:49:03 INFO - PROCESS | 23478 | RSS: Main: 231047168 11:49:03 INFO - PROCESS | 23478 | 11:49:05 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:49:06 INFO - PROCESS | 23478 | RSS: Main: 238039040 11:49:06 INFO - PROCESS | 23478 | 11:49:09 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:49:09 INFO - PROCESS | 23478 | RSS: Main: 241184768 11:49:09 INFO - PROCESS | 23478 | 11:49:12 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:49:13 INFO - PROCESS | 23478 | RSS: Main: 240160768 11:49:13 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | Cycle 1(1): loaded http://localhost:40232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40232/tests/kraken/driver.html?testName=ai-astar) 11:49:15 INFO - PROCESS | 23478 | RSS: Main: 283267072 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | __start_tp_report 11:49:15 INFO - PROCESS | 23478 | _x_x_mozilla_page_load 11:49:15 INFO - PROCESS | 23478 | _x_x_mozilla_page_load_details 11:49:15 INFO - PROCESS | 23478 | |i|pagename|runs| 11:49:15 INFO - PROCESS | 23478 | |0;ai-astar;113;107;113;113;114;114;114;112;113;112 11:49:15 INFO - PROCESS | 23478 | |1;audio-beat-detection;157;130;137;182;139;162;161;131;190;141 11:49:15 INFO - PROCESS | 23478 | |2;audio-dft;161;162;163;161;168;163;162;161;162;161 11:49:15 INFO - PROCESS | 23478 | |3;audio-fft;78;75;77;115;79;77;76;76;98;76 11:49:15 INFO - PROCESS | 23478 | |4;audio-oscillator;89;86;119;86;86;88;123;86;86;134 11:49:15 INFO - PROCESS | 23478 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;116;117;117 11:49:15 INFO - PROCESS | 23478 | |6;imaging-darkroom;146;147;146;146;146;146;146;145;146;146 11:49:15 INFO - PROCESS | 23478 | |7;imaging-desaturate;96;96;95;96;96;95;95;95;96;95 11:49:15 INFO - PROCESS | 23478 | |8;json-parse-financial;64;68;64;64;65;64;68;64;63;74 11:49:15 INFO - PROCESS | 23478 | |9;json-stringify-tinderbox;65;65;65;66;66;66;66;66;65;64 11:49:15 INFO - PROCESS | 23478 | |10;stanford-crypto-aes;83;90;83;84;86;85;84;83;113;83 11:49:15 INFO - PROCESS | 23478 | |11;stanford-crypto-ccm;135;133;132;141;135;143;133;141;139;137 11:49:15 INFO - PROCESS | 23478 | |12;stanford-crypto-pbkdf2;171;169;173;175;174;171;172;175;169;172 11:49:15 INFO - PROCESS | 23478 | |13;stanford-crypto-sha256-iterative;67;68;68;71;67;66;67;65;66;65 11:49:15 INFO - PROCESS | 23478 | __end_tp_report 11:49:15 INFO - PROCESS | 23478 | __start_cc_report 11:49:15 INFO - PROCESS | 23478 | _x_x_mozilla_cycle_collect,1890 11:49:15 INFO - PROCESS | 23478 | __end_cc_report 11:49:15 INFO - PROCESS | 23478 | __startTimestamp1466534955391__endTimestamp 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | ------- Summary: start ------- 11:49:15 INFO - PROCESS | 23478 | Number of tests: 14 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:2.07 (1.8%) stddev-sans-first:2.19 11:49:15 INFO - PROCESS | 23478 | Values: 113.0 107.0 113.0 113.0 114.0 114.0 114.0 112.0 113.0 112.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#1] audio-beat-detection Cycles:10 Average:153.00 Median:159.00 stddev:21.03 (13.2%) stddev-sans-first:22.25 11:49:15 INFO - PROCESS | 23478 | Values: 157.0 130.0 137.0 182.0 139.0 162.0 161.0 131.0 190.0 141.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:2.12 (1.3%) stddev-sans-first:2.19 11:49:15 INFO - PROCESS | 23478 | Values: 161.0 162.0 163.0 161.0 168.0 163.0 162.0 161.0 162.0 161.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#3] audio-fft Cycles:10 Average:82.70 Median:77.00 stddev:13.22 (17.2%) stddev-sans-first:13.91 11:49:15 INFO - PROCESS | 23478 | Values: 78.0 75.0 77.0 115.0 79.0 77.0 76.0 76.0 98.0 76.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:86.00 stddev:19.04 (22.1%) stddev-sans-first:19.89 11:49:15 INFO - PROCESS | 23478 | Values: 89.0 86.0 119.0 86.0 86.0 88.0 123.0 86.0 86.0 134.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:49:15 INFO - PROCESS | 23478 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 11:49:15 INFO - PROCESS | 23478 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 11:49:15 INFO - PROCESS | 23478 | Values: 96.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:64.50 stddev:3.36 (5.2%) stddev-sans-first:3.50 11:49:15 INFO - PROCESS | 23478 | Values: 64.0 68.0 64.0 64.0 65.0 64.0 68.0 64.0 63.0 74.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 11:49:15 INFO - PROCESS | 23478 | Values: 65.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 65.0 64.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:84.00 stddev:9.25 (11.0%) stddev-sans-first:9.68 11:49:15 INFO - PROCESS | 23478 | Values: 83.0 90.0 83.0 84.0 86.0 85.0 84.0 83.0 113.0 83.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#11] stanford-crypto-ccm Cycles:10 Average:136.90 Median:138.00 stddev:3.90 (2.8%) stddev-sans-first:4.08 11:49:15 INFO - PROCESS | 23478 | Values: 135.0 133.0 132.0 141.0 135.0 143.0 133.0 141.0 139.0 137.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:172.50 stddev:2.18 (1.3%) stddev-sans-first:2.28 11:49:15 INFO - PROCESS | 23478 | Values: 171.0 169.0 173.0 175.0 174.0 171.0 172.0 175.0 169.0 172.0 11:49:15 INFO - PROCESS | 23478 | 11:49:15 INFO - PROCESS | 23478 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.76 (2.6%) stddev-sans-first:1.87 11:49:15 INFO - PROCESS | 23478 | Values: 67.0 68.0 68.0 71.0 67.0 66.0 67.0 65.0 66.0 65.0 11:49:15 INFO - PROCESS | 23478 | -------- Summary: end -------- 11:49:15 INFO - PROCESS | 23478 | 11:49:16 INFO - TEST-INFO | 23478: exit 0 11:49:16 INFO - TEST-OK | kraken | took 64002ms 11:49:16 INFO - SUITE-END | took 1183s 11:49:17 INFO - Completed test suite (00:19:44) 11:49:17 INFO - javascript benchmark 11:49:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2655.628212050313, 2677.397585543252, 2700.162949485659, 2709.796765242606, 2717.415755612543, 4591.908161836761, 4672.766361681919, 4753.8811529711775, 4875.683080599759, 4930.580900623447, 4779.402574678165, 4803.3995750531185, 4876.926846097312, 4895.55939965403, 4965.553100220979, 4350.825966961318, 4362.78186090695, 4431.6233120184825, 4495.730256184623, 4613.838515651953, 4515.4129963104915, 4549.977250113745, 4565.497795242521, 4576.0, 4588.7935042923145, 4237.555056719031, 4264.978675106636, 4278.721883077621, 4420.6242469390145, 4430.933535996963, 4362.47650281965, 4367.519572846984, 4374.78126093694, 4443.333499974998, 4443.822247110112, 4399.38408622793, 4400.053988392479, 4402.273624851896, 4406.779661016936, 4440.7335559866515, 4639.257718764981, 4726.361941137937, 4787.162246606865, 4789.37738094049, 4841.104395686774, 5151.5621172200035, 5208.0, 5255.290535777659, 5257.0274499217385, 5262.421133675292, 5222.268882356473, 5225.346831646044, 5254.579633629301, 5279.788808447657, 5281.894362112775, 4687.6249900007915, 4782.521747825259, 4809.470958194595, 4912.38595175603, 4918.975405123023, 5316.229146773696, 5317.1226747586215, 5344.812931547377, 5353.625246232727, 5366.221897824794, 5085.389753229562, 5086.491350864884, 5129.076766182048, 5139.152039913447, 5142.04872098663, 4932.975335123301, 5034.697918124924, 5071.46749591295, 5074.0, 5231.398389185217, 5042.016806722727, 5050.898982020339, 5062.265971434122, 5063.367079115111, 5116.411612664591, 5025.170846810309, 5031.094403007421, 5033.84898453047, 5034.219695947134, 5037.899242015213, 4252.532221455637, 4289.378040184219, 4289.785510724451, 4341.45731783527, 4350.891227719332, 3786.3184626767447, 3814.7520411173186, 3815.599362066943, 3830.597787232356, 3830.712696547714, 4337.0, 4353.651707863363, 4356.716813407182, 4379.364992076131, 4398.780060996937, 5177.585793136615, 5193.246979187997, 5200.8959820803375, 5201.245819356172, 5243.292155559027, 4399.824007039747, 4400.713953593006, 4400.845970391021, 4436.955630443719, 4446.555344465528, 4479.731216127043, 4599.563041511051, 4620.237660786, 4646.349511068385, 4651.139539185261, 4833.10587541306, 4838.056578967067, 4847.321375007502, 4851.46633870281, 4886.071646387317, 5112.948870511396, 5124.052050370693, 5155.0, 5172.9741351293005, 5226.085435048927, 4707.388039554836, 4738.170820106537, 4764.30917516965, 4785.138675038526, 4805.1590971579135, 5034.84895453137, 5048.0, 5049.545540901337, 5053.090443720165, 5108.7445627717725, 4990.925136122888, 5110.66780659256, 5121.871953201051, 5133.0, 5169.844904652867, 4969.503049695001, 5018.272350509083, 5035.723035232951, 5036.093503169464, 5063.797448102035, 5041.0422019816115, 5046.495350465071, 5048.873778155576, 5051.090803655377, 5097.362829646294, 4698.812047518198, 4880.438749543848, 4971.825986090469, 4986.301917731593, 5037.420696619935, 5041.722705251246, 5096.51583099605, 5096.796128154832, 5119.6416250862085, 5145.202493613496, 4976.502349765139, 4992.675476094042, 4997.950020499894, 5000.1749711297225, 5051.494850515066], "value": 4721.453622212005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.2645039307394, 567.1662655895833, 602.0908428273311, 606.690587800222, 617.817743765589, 28670.0, 31847.203819904484, 31867.52198717021, 39089.0, 40574.0, 40574.59425405745, 41138.38292425616, 41191.17617647645, 41234.58765412345, 41262.58737412625, 7707.190744971781, 7867.6852925882895, 7868.645910934022, 7871.960640196791, 7872.252136047066, 7638.961805190994, 7739.729109481141, 7741.5355078695175, 7747.0, 7747.96126019372, 7716.961415192916, 7754.8449031019345, 7780.066392032964, 7780.5720685362285, 7785.766427007199, 7609.581473018981, 7651.273129052786, 7695.653695583712, 7711.383089352895, 7718.575478348688, 7693.0, 7776.65005074773, 7776.844463110762, 7782.066152061732, 7787.06555213378, 38240.0, 38578.22843543142, 38585.80707096474, 38627.0, 38644.61355386438, 3165.271987442901, 3239.82180980046, 3249.5938007749032, 3373.595168579762, 3538.6815186633326, 29432.70567294321, 29726.40547189044, 29905.102846914626, 30074.84962575195, 30332.54501182484, 3477.634848340913, 3570.250247448039, 3575.803330816804, 3586.5337506124038, 3594.4428613564937, 250366.0, 250858.0, 251291.0, 251320.0, 251568.0, 30225.0, 51345.486545134816, 51454.0, 51807.48192518102, 51837.0, 31414.214644634067, 33546.832265839, 33623.83188084093, 33713.831430842685, 33753.0, 13630.114042587398, 13905.86094139066, 13915.30423478822, 13925.373358198905, 13929.373178207004, 11073.114150867912, 11210.495527701272, 11215.327080375204, 11219.775604488028, 11221.71945701364, 7819.921800782033, 7840.607969601497, 7900.301435191346, 7955.124936257007, 7966.880496792553, 3085.953710694341, 3098.690130986883, 3130.1235654016914, 3138.0, 3152.9684703152675, 929.3448119076036, 951.7382719752123, 968.6658102954469, 971.5336638413601, 973.5862258540234, 26431.867840660674, 27474.17577472679, 27958.301042473693, 28060.85969570139, 28187.859060704566, 866.7486428935664, 918.0773322810533, 977.8900947424725, 1009.6062535611118, 1024.3393011507535, 14223.85776142246, 14509.889934598912, 14580.781288280685, 14712.33794479251, 14834.777478337834, 392604.0, 393364.0, 397853.0, 399079.0, 401975.0, 46430.30354544685, 47053.0, 47101.764491177324, 47122.29316560255, 47155.0, 1081.1404933078222, 1173.202222488699, 1189.5301355964345, 1192.469351138735, 1204.0728638948135, 27001.729982699922, 28726.281842954093, 28875.5668664966, 29241.122766317043, 29320.853395732884, 41247.58752412558, 41519.58480415278, 41966.79016604897, 41983.79008105062, 42079.7896010518, 307590.0, 309589.0, 309968.0, 310480.0, 310566.0, 21878.23426180076, 23456.41358965971, 24230.75769242285, 24384.878075609508, 24820.37949051288, 40146.59853401545, 40352.596474034886, 40443.19113617652, 41207.58792412037, 42193.156136876474, 34147.31705365829, 36519.63480365162, 37076.44385334276, 37191.070223244635, 37214.255714886094, 36090.278194435436, 36864.44703329506, 37071.44392834056, 37122.0, 37244.627553724116, 36614.450783238804, 36761.77239617478, 37105.443418349285, 37223.62776372202, 37375.0, 31731.0, 33278.334433310716, 33359.83320083384, 33388.66611333856, 33663.0, 25313.493730124927, 25334.239972800846, 25644.61533076968, 25649.615255770805, 26071.73928260769], "value": 16436.865456004147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.59160128058494, 371.41131306878594, 379.8670465337134, 386.4936932618272, 388.1635076410335, 15613.765793513092, 16838.326466941322, 17976.280948762065, 17980.82019179811, 17985.91007044963, 11229.663110106689, 15718.842811571909, 16583.0, 16590.75113873293, 17253.396131135414, 4621.537846215377, 4790.760461976887, 4827.10698520773, 4832.492588278233, 4834.1540230459705, 3469.930601387971, 5260.631755777097, 5575.944240557604, 5586.748596313156, 5669.6314739541795, 5436.619436639437, 5610.887782244353, 5619.438056194368, 5628.0713682242385, 5639.605227634067, 5611.410801865786, 5651.378348381675, 5654.180143879114, 5687.118496633028, 5695.515881150107, 3351.262722201112, 5081.8475445736685, 5384.111621582434, 5397.163439666819, 5482.0, 12590.181638193488, 12594.74810503794, 12604.11771176018, 18462.44612661622, 19626.607467850707, 1906.5996140810446, 2078.6362386582437, 2084.405944305881, 2172.652375619893, 2404.9398765031015, 9182.08179182083, 9198.494082825442, 9738.90261097387, 10157.339772914736, 13747.725045499034, 1740.3995621510558, 1770.672425601255, 1779.8932064076198, 1845.1051240148518, 1893.943181704551, 61956.0, 69121.65439172823, 69231.0, 70159.0, 71569.28430715678, 10174.898251017543, 10294.794104118026, 10377.9481102594, 10429.165666746641, 10549.525271362654, 14184.64538386528, 14230.86153107749, 14266.215358155194, 14276.42959274479, 14279.0, 7348.375388091965, 7740.109887362913, 7745.225477452323, 7771.145174030852, 7874.291313781787, 7342.77971660851, 7375.631218439056, 7556.4710470266555, 7580.431467639949, 7589.658465369072, 4422.823087076546, 4467.508574056851, 4528.773561321921, 4540.0, 4622.791974361162, 1722.7071397862394, 1760.9383671571434, 1876.7278744581959, 1902.4958386027713, 1935.458071739915, 772.3049255669943, 841.5329492131925, 865.2731705367521, 881.1452890696015, 978.1343510992823, 16287.104209268367, 16622.75065874013, 16678.58303542421, 16822.57943551421, 17382.3047078118, 828.8093738440192, 838.8406202821567, 871.7776966873533, 892.3485855325519, 910.7855401670299, 9434.339596228197, 9529.285303602257, 9648.71053868385, 9667.758306042264, 9877.160441362565, 381938.0, 388219.0, 391489.0, 391632.0, 391761.0, 75375.0, 77542.0, 80076.59961700154, 82472.58763706143, 83268.0, 832.3881946769241, 876.9123087691307, 892.1168043636753, 902.9909700902907, 905.2848249882519, 15129.167895765839, 15286.770698439756, 15410.537683869501, 15455.768163477333, 15581.532554023397, 36921.63078369182, 37120.77275363487, 37236.0, 37466.625333747405, 37575.624243758306, 50917.0, 52120.218196726324, 53994.19008714794, 53997.0, 54039.0, 7778.338841198335, 7798.0, 7892.486988345969, 8066.314363278952, 8071.4753541019645, 9015.549222538977, 10980.890191097988, 11977.520899163936, 12052.276863388224, 12407.44166512491, 9533.47565883883, 9862.85205721929, 10312.690619281433, 10824.891751082389, 10874.130069594255, 10636.361818290618, 10683.30558513694, 10724.73188170302, 10854.565817367216, 11233.101351891992, 10114.544845481823, 10359.94820025895, 10524.684259472227, 10574.10120139766, 11248.718782030515, 7691.961540192263, 7970.2826745593175, 7978.165022044846, 8059.43583949118, 8099.230573095547, 7765.805854853448, 9908.702738917844, 10082.193424526167, 10371.481425928825, 10413.9479302603], "value": 8239.716156653642, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.3339996401944, 1365.5698454986675, 1399.1674953402726, 1454.7526920423527, 1703.3527259641342, 6867.210270818858, 7597.202293759152, 7872.685092596289, 7879.527228366302, 8151.225633564816, 7849.489783164105, 7949.483283586563, 7981.6807327706965, 8025.0, 8052.114267430578, 1899.4681489183051, 2196.242296407737, 2222.355516900097, 2224.733032036158, 2228.665700144983, 2167.3172950520534, 2233.6761169630395, 2235.072444935358, 2250.17868478006, 2252.538229662923, 2132.936011919645, 2157.2126173946476, 2158.2985529702833, 2162.8810415427147, 2167.1656412281304, 1850.9167087480962, 2094.3821572636107, 2185.0604240176717, 2195.923142690014, 2196.5826492966444, 2164.3074216250807, 2211.137656314039, 2218.279059305716, 2237.5860465813876, 2240.596692595332, 7929.5242285463055, 7941.523508589503, 7966.760997170094, 8003.15966823481, 8012.3990700697095, 4554.590086892195, 4676.859694209179, 4679.578837904571, 4684.859454216379, 4687.765611719435, 2977.077106097117, 3033.6662967073603, 3072.0, 3082.414341275173, 3092.6443459002276, 1017.5522769981185, 1054.3726482742832, 1075.1345167140448, 1078.7734575739105, 1085.5006696919427, 10762.838557421723, 10785.352878827218, 10841.078508326802, 10858.45707714619, 10879.945600272027, 4129.525104613008, 4241.1941731071, 4295.269804133305, 4367.5414081520985, 4371.475422949203, 9086.681966131153, 9170.082991700776, 9178.86231706525, 9181.265498760084, 9189.81620367589, 4676.0, 4722.929156062662, 4752.5485078917445, 4758.3100450434495, 4758.928616070762, 3713.7400381973002, 4159.9376009359885, 4192.727472714325, 4252.4046633471335, 4304.741715497017, 3662.3957047087465, 3681.2269423420844, 3704.5739739930254, 3759.285735710206, 3767.3407153747985, 1165.9825102623468, 1199.698327314873, 1215.6231568213882, 1221.1818081885158, 1224.13086708438, 841.8905542279465, 872.289084396215, 885.57049830832, 891.0465801592234, 911.293747345799, 5613.0, 5639.971800141055, 5655.5475561954945, 5739.8565035874435, 5944.910826337608, 852.957352132391, 862.2024627219797, 863.2748491267365, 872.1322284327008, 924.713338864954, 3870.2259548090497, 3984.940225896614, 4001.419794129835, 4023.7183397161916, 4052.5339585947995, 9491.477968711788, 9715.0, 9874.913759486451, 9888.851667224997, 9926.553305101288, 9792.314537982415, 9792.657256996114, 9819.410835349901, 9834.901650983542, 9845.655402060893, 7009.7897063088185, 7283.543291341753, 7325.304096110967, 7419.07261592301, 7436.442266830009, 5687.857803554944, 5723.141528770718, 5815.76736930518, 5823.679697616672, 5903.734331954986, 8307.792305192419, 8975.50634715097, 9097.272218222659, 9103.772405689911, 9231.307651925998, 8885.91114088851, 9046.411983221069, 9119.361644684808, 9146.908530914605, 9173.0, 6214.316425193316, 6396.552241343247, 6404.103425520338, 6870.656467176521, 7047.048648432396, 6801.353871382211, 6948.617826019416, 7662.885056724265, 7694.307512323918, 7695.345895598713, 6763.2898545652, 6811.148606424197, 6940.514164008471, 7029.648517574203, 7036.648167591702, 6758.324167583202, 6772.762953296611, 6784.423324017514, 6883.586984780929, 6917.9308206917285, 6547.181602299713, 6607.537472376887, 6678.699458524281, 6682.63245521501, 6687.164104486939, 4742.52574742523, 4830.95169048305, 4851.417829860439, 4854.247591623304, 4898.191798353232, 4927.852164435073, 4956.900861982812, 5025.47232540578, 5047.015831912742, 5055.69665820052], "value": 4245.081540612806, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.92300963254365, 305.38922155688624, 307.88300445830583, 308.56954548404974, 312.20699423464396, 4781.976090119549, 4880.170371036923, 4892.951070489298, 4896.094222568827, 4896.804127834891, 4761.2143996240575, 4802.279658051285, 4811.10994466024, 4817.796165549914, 4820.421549414075, 3810.637989391017, 3815.4276858471244, 3828.0812604974744, 3848.9992601923423, 3863.80680965952, 3687.15195505034, 3823.21624067067, 3860.768353898775, 3862.555806082308, 3868.9613103869024, 3689.6863766579872, 3778.6599206071446, 3810.627594578804, 3810.8285127169206, 3831.67430768385, 3776.678982286509, 3777.263433630443, 3788.488554045196, 3792.4880141181, 3804.8097595120134, 3174.6190357232076, 3261.4781634938295, 3268.4280250956226, 3277.475603903364, 3305.0004747128123, 4304.225239456897, 4533.886652833673, 4687.414073240845, 4719.197736384788, 4726.409198850144, 4314.697971142022, 4315.2738904438165, 4326.875012496742, 4340.8480703175555, 4340.872972243609, 4682.953170468286, 5058.494150584949, 5068.138416469209, 5075.046107248699, 5079.415867175285, 3514.859405623772, 3640.5631324241263, 3644.1982763791925, 3651.178484840916, 3652.7077833773233, 4999.400071991348, 5052.343195384577, 5135.922961155586, 5213.478652134794, 5224.973875130676, 3676.6690997810324, 3677.9264414711556, 3678.3562876496508, 3684.0421490412687, 3693.796841173761, 3744.1388480649603, 3752.7373083883867, 3754.249150169977, 3765.1904840459433, 3771.6228377162065, 4126.514454796271, 4184.0, 4245.97877010613, 4260.0, 4265.722728022669, 5007.574356179765, 5010.824621138316, 5012.649114561946, 5012.724300163453, 5015.22264049073, 1280.327827890365, 1314.3953781260727, 1318.3803612302202, 1328.860469650692, 1332.4403750424844, 822.9547374894438, 854.9358798090168, 863.9049704532497, 864.2481041493941, 865.5628907401722, 3940.3402703175243, 3943.6056394360335, 3967.028078120821, 3973.7417067890497, 3978.8660231833787, 4518.367428560005, 4542.523035081335, 4559.4072770539615, 4569.977150114228, 4583.977080114645, 3939.428782826474, 3942.6397892726964, 4017.216642754635, 4020.4974378202724, 4043.9393409098298, 2481.8634975076184, 2524.7475252474605, 2745.6018877262686, 2757.352022274764, 2761.8895244190007, 4778.426588809296, 4791.784369703371, 4821.590164835958, 4828.179209534387, 4844.685095468783, 5242.213667949838, 5247.842564723064, 5249.606279529051, 5260.473952604709, 5288.735563221823, 4632.305154226893, 4830.99999999993, 4854.495106516991, 4857.0, 4902.607791376681, 5172.767225474788, 5178.067947769287, 5183.486009285418, 5191.584673226057, 5219.121116398018, 4912.354361289008, 5115.232715092617, 5115.92326115116, 5122.0, 5162.896742065062, 4676.906461870675, 4770.952290477189, 4804.591609713214, 4811.374521312207, 4943.406791184937, 3526.4181410067054, 3579.087332730137, 3659.5425571803526, 3683.134463401045, 3711.3690672586267, 5116.692998420107, 5130.8973820524125, 5136.332276803992, 5160.200168973815, 5176.327077479904, 4895.853124406273, 4926.078829256521, 4997.775100120576, 5008.198688209869, 5008.724520151281, 4941.456439791692, 4966.428860680923, 4969.602431805519, 4986.950130498649, 4988.201887697951, 4899.289603007615, 4904.166291730486, 4919.409670839522, 4924.581410580141, 4931.235658472942, 4867.756612169448, 4998.225275082368, 5018.247262910447, 5020.569137795711, 5022.824201152942, 4882.780274887567, 4885.853424397274, 4906.852794416173, 4914.950850491449, 4927.507249275044], "value": 3755.5968170351944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.3170008091338, 648.2545073165861, 685.8765422224001, 722.3246264742464, 731.2358585278383, 12549.623511294665, 13538.864611353883, 13549.051566390357, 13553.52562660306, 13564.05051646385, 12287.38563071845, 12402.069844761656, 12439.751204975895, 12453.190542614724, 12481.126321157522, 626.3235705438116, 767.87330090535, 771.255738212626, 783.165928286376, 796.0646240667193, 791.0467886197148, 799.2207597592359, 799.7121036426911, 802.5666140284239, 802.7070119406404, 824.1634740738156, 830.6511265268601, 831.2477208126655, 835.8119423129808, 838.5010918503481, 648.1929997153527, 803.9990212185808, 825.5459497276474, 829.3323874281201, 839.17760594617, 613.4111253196936, 613.5582380685898, 613.6808859393135, 613.9416755408229, 613.9907901381483, 13557.118787278796, 13603.0, 13726.931365343109, 13744.312784360742, 13748.518801841887, 12727.236365817987, 13326.466941322335, 13473.528426505025, 13490.0, 13492.0, 11515.490481992665, 11613.76772464546, 11620.477078531487, 11767.23512971654, 11798.882011179865, 3282.0, 3307.8842240521467, 3398.9660103398896, 4237.4915010198665, 4337.349397590387, 59420.70289648567, 60142.699286503725, 60173.39826601722, 60373.69813150906, 60746.696266518826, 10271.9486402569, 10291.612335946329, 10527.157827373792, 10668.573257069786, 10670.199735019905, 17839.643207135785, 17846.732299015526, 17868.642627147383, 17874.46376608704, 17885.105744712713, 6655.434288085567, 6769.932300677028, 6793.694283757242, 6865.038894554767, 6970.512064155562, 3709.0356507308293, 3784.7161462890394, 3818.59904710007, 3824.388097904322, 3855.0747820522874, 2925.5611658251005, 3014.427258820817, 3015.517517197238, 3042.4371491274187, 3053.2977415194187, 523.2334629767333, 532.6484520216638, 535.3361831329202, 536.1368197202501, 540.835045311176, 553.4299671338447, 583.821934310031, 589.0957380421033, 590.3152343281772, 600.4115966352998, 7968.840623187504, 8060.1133875274845, 8104.473209241381, 8382.455140415854, 8426.19218787452, 455.5672410269683, 475.60049558371134, 566.1309889319874, 591.3347484080409, 593.1073734030257, 3754.4180651998986, 3813.3517302058717, 3849.9037524062123, 3973.940390894139, 4018.2365350583295, 54654.72672636611, 54753.0, 55390.72304638451, 55390.72304638532, 55669.0, 46720.5327946723, 47473.28790068152, 47790.0, 80155.59922200351, 82938.0, 43962.78018609886, 57005.0, 69156.6542167286, 69205.30794692089, 73476.63261683764, 8469.153084691729, 8525.91474085276, 8741.945642773815, 8798.38411311202, 8851.911480885108, 33655.32689346249, 39276.0, 39297.410538841366, 39633.60366396299, 40660.0, 41818.0, 60224.39775602363, 61388.0, 62975.6851215741, 64369.35630643821, 10928.50821713009, 11268.15488838324, 11290.266132701348, 11459.828102578635, 11601.82597261025, 12063.276203427822, 12159.088068394729, 12191.329476878856, 13115.868841311465, 13161.802572961222, 10240.0, 12424.192427492546, 12480.126391152895, 12660.739799968293, 12722.363881806057, 12000.699982500508, 12060.03519718438, 12158.088143389105, 12168.08739344535, 12465.439055242354, 11415.486303116546, 11772.17594768358, 11785.410729463321, 11788.4695188715, 11789.292642441482, 9382.343235973416, 9549.9045009549, 9580.377275477073, 9834.852477212704, 9840.507974601385, 9159.633614655339, 9330.906690933003, 9493.905060949302, 9661.613535458502, 9806.509674516388], "value": 6377.996241684562, "unit": "score"}], "name": "dromaeo_css", "value": 6341.3794819082605, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 113.0, 113.0, 114.0, 114.0, 114.0, 112.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 130.0, 137.0, 182.0, 139.0, 162.0, 161.0, 131.0, 190.0, 141.0], "value": 153.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 168.0, 163.0, 162.0, 161.0, 162.0, 161.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 77.0, 115.0, 79.0, 77.0, 76.0, 76.0, 98.0, 76.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 119.0, 86.0, 86.0, 88.0, 123.0, 86.0, 86.0, 134.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 64.0, 64.0, 65.0, 64.0, 68.0, 64.0, 63.0, 74.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 64.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 90.0, 83.0, 84.0, 86.0, 85.0, 84.0, 83.0, 113.0, 83.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 133.0, 132.0, 141.0, 135.0, 143.0, 133.0, 141.0, 139.0, 137.0], "value": 136.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 173.0, 175.0, 174.0, 171.0, 172.0, 175.0, 169.0, 172.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 71.0, 67.0, 66.0, 67.0, 65.0, 66.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1561.8000000000002, "alertThreshold": 2.0}]} 11:49:17 INFO - Return code: 0 11:49:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:49:17 INFO - # TBPL SUCCESS # 11:49:17 INFO - Running post-action listener: _resource_record_post_action 11:49:17 INFO - ##### 11:49:17 INFO - ##### Finished run-tests step (success) 11:49:17 INFO - ##### 11:49:17 INFO - Running post-run listener: _resource_record_post_run 11:49:17 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 317317120; Read time: 0; Write time: 181224 11:49:17 INFO - TinderboxPrint: CPU usage
13.1% 11:49:17 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:49:17 INFO - TinderboxPrint: I/O write bytes / time
317,317,120 / 181,224 11:49:17 INFO - TinderboxPrint: CPU idle
8,322.5 (86.8%) 11:49:17 INFO - TinderboxPrint: CPU user
1,215.8 (12.7%) 11:49:17 INFO - TinderboxPrint: Swap in / out
0 / 0 11:49:17 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 33984512; Read time: 0; Write time: 7932 11:49:17 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 283332608; Read time: 0; Write time: 173292 11:49:17 INFO - Running post-run listener: _upload_blobber_files 11:49:17 INFO - Blob upload gear active. 11:49:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:49:17 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 11:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:49:18 INFO - (blobuploader) - INFO - Open directory for files ... 11:49:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:49:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:49:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:21 INFO - (blobuploader) - INFO - Done attempting. 11:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:49:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:22 INFO - (blobuploader) - INFO - Done attempting. 11:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:49:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:23 INFO - (blobuploader) - INFO - Done attempting. 11:49:23 INFO - (blobuploader) - INFO - Iteration through files over. 11:49:23 INFO - Return code: 0 11:49:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:49:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:49:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/babf218dc02034ad6abdd5760e445a1fa356172ebdbddcadbcd4c58c8c909af76a89e66cbe9a2c56749876aee5536f37d67d7d54c43a18a1067f8164c9e0e0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff0dc945ed4af551fec3261e5fea7a4ed91673e22f93c59b598dce6c9cd1f67324a79de3b97eb6e36e896af5e632086562c1b01218e416159f6e7c17cebc39c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60fbf6376e9aba9ab5f5ab6fd3910894f721ec47ce8f1e7456982e94e973160ce96d58e7a9654c6e79a57e2b873ef645ba17688902948ac207bc098a814d2ec9"} 11:49:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:49:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:49:23 INFO - Contents: 11:49:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/babf218dc02034ad6abdd5760e445a1fa356172ebdbddcadbcd4c58c8c909af76a89e66cbe9a2c56749876aee5536f37d67d7d54c43a18a1067f8164c9e0e0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff0dc945ed4af551fec3261e5fea7a4ed91673e22f93c59b598dce6c9cd1f67324a79de3b97eb6e36e896af5e632086562c1b01218e416159f6e7c17cebc39c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60fbf6376e9aba9ab5f5ab6fd3910894f721ec47ce8f1e7456982e94e973160ce96d58e7a9654c6e79a57e2b873ef645ba17688902948ac207bc098a814d2ec9"} 11:49:23 INFO - Running post-run listener: copy_logs_to_upload_dir 11:49:23 INFO - Copying logs to upload dir... 11:49:23 INFO - mkdir: /builds/slave/test/build/upload/logs 11:49:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1308.059060 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2016-06-21 11:49:23.172489) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 11:49:23.176647) ========= 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=20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/babf218dc02034ad6abdd5760e445a1fa356172ebdbddcadbcd4c58c8c909af76a89e66cbe9a2c56749876aee5536f37d67d7d54c43a18a1067f8164c9e0e0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff0dc945ed4af551fec3261e5fea7a4ed91673e22f93c59b598dce6c9cd1f67324a79de3b97eb6e36e896af5e632086562c1b01218e416159f6e7c17cebc39c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60fbf6376e9aba9ab5f5ab6fd3910894f721ec47ce8f1e7456982e94e973160ce96d58e7a9654c6e79a57e2b873ef645ba17688902948ac207bc098a814d2ec9"} build_url:https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011384 build_url: 'https://queue.taskcluster.net/v1/task/Qoba_Y4YR7qXNRAbEQm4rQ/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/Firefox-Non-PGO/sha512/babf218dc02034ad6abdd5760e445a1fa356172ebdbddcadbcd4c58c8c909af76a89e66cbe9a2c56749876aee5536f37d67d7d54c43a18a1067f8164c9e0e0a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff0dc945ed4af551fec3261e5fea7a4ed91673e22f93c59b598dce6c9cd1f67324a79de3b97eb6e36e896af5e632086562c1b01218e416159f6e7c17cebc39c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60fbf6376e9aba9ab5f5ab6fd3910894f721ec47ce8f1e7456982e94e973160ce96d58e7a9654c6e79a57e2b873ef645ba17688902948ac207bc098a814d2ec9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 11:49:23.204633) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:49:23.205256) ========= 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=20e666a3d4cfbb71505a330e000001b4-1466533653.758735-1818962794 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 11:49:23.285452) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 11:49:23.285840) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 11:49:23.286219) ========= ========= Total master_lag: 0.18 =========