builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1467540960.6 results: success (0) buildid: 20160703003821 builduid: 0d6d77990de04ab99b037c124c572879 revision: c25ec152b6eff61053d6d040b923baf8716abb37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.605102) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.605593) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.605869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.636423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.636764) ========= 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=fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-03 03:16:02-- 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% 38.7M=0s 2016-07-03 03:16:02 (38.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.780282) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.780576) ========= 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=fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021626 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:00.831381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 03:16:00.831780) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c25ec152b6eff61053d6d040b923baf8716abb37 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c25ec152b6eff61053d6d040b923baf8716abb37 --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=fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-03 03:16:02,842 truncating revision to first 12 chars 2016-07-03 03:16:02,842 Setting DEBUG logging. 2016-07-03 03:16:02,842 attempt 1/10 2016-07-03 03:16:02,842 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c25ec152b6ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-03 03:16:03,723 unpacking tar archive at: mozilla-aurora-c25ec152b6ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.229891 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-03 03:16:02.077318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:02.077649) ========= script_repo_revision: c25ec152b6eff61053d6d040b923baf8716abb37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:02.078044) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:02.078306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-03 03:16:02.087353) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-07-03 03:16:02.087732) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:04 INFO - MultiFileLogger online at 20160703 03:16:04 in /builds/slave/test 03:16:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:16:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:04 INFO - {'append_to_log': False, 03:16:04 INFO - 'base_work_dir': '/builds/slave/test', 03:16:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:16:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:04 INFO - 'branch': 'Mozilla-Aurora', 03:16:04 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:04 INFO - 'config_files': ('talos/linux_config.py',), 03:16:04 INFO - 'default_actions': ('clobber', 03:16:04 INFO - 'read-buildbot-config', 03:16:04 INFO - 'download-and-extract', 03:16:04 INFO - 'populate-webroot', 03:16:04 INFO - 'create-virtualenv', 03:16:04 INFO - 'install', 03:16:04 INFO - 'run-tests'), 03:16:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:04 INFO - 'download_minidump_stackwalk': True, 03:16:04 INFO - 'download_symbols': 'ondemand', 03:16:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:16:04 INFO - 'tooltool.py': '/tools/tooltool.py', 03:16:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:16:04 INFO - '/tools/misc-python/virtualenv.py')}, 03:16:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:04 INFO - 'installer_path': 'installer.exe', 03:16:04 INFO - 'log_level': 'info', 03:16:04 INFO - 'log_name': 'talos', 03:16:04 INFO - 'log_to_console': True, 03:16:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:04 INFO - 'opt_config_files': (), 03:16:04 INFO - 'pip_index': False, 03:16:04 INFO - 'sps_profile': False, 03:16:04 INFO - 'sps_profile_interval': 0, 03:16:04 INFO - 'suite': 'dromaeojs', 03:16:04 INFO - 'system_bits': '32', 03:16:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:04 INFO - 'title': 'talos-linux64-ix-048', 03:16:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:04 INFO - 'use_talos_json': True, 03:16:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:04 INFO - 'work_dir': 'build'} 03:16:04 INFO - ##### 03:16:04 INFO - ##### Running clobber step. 03:16:04 INFO - ##### 03:16:04 INFO - Running pre-action listener: _resource_record_pre_action 03:16:04 INFO - Running main action method: clobber 03:16:04 INFO - rmtree: /builds/slave/test/build 03:16:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:04 INFO - Running post-action listener: _resource_record_post_action 03:16:04 INFO - ##### 03:16:04 INFO - ##### Finished clobber step (success) 03:16:04 INFO - ##### 03:16:04 INFO - ##### 03:16:04 INFO - ##### Running read-buildbot-config step. 03:16:04 INFO - ##### 03:16:04 INFO - Running pre-action listener: _resource_record_pre_action 03:16:04 INFO - Running main action method: read_buildbot_config 03:16:04 INFO - Using buildbot properties: 03:16:04 INFO - { 03:16:04 INFO - "project": "", 03:16:04 INFO - "product": "firefox", 03:16:04 INFO - "script_repo_revision": "production", 03:16:04 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:16:04 INFO - "repository": "", 03:16:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:16:04 INFO - "buildid": "20160703003821", 03:16:04 INFO - "pgo_build": "True", 03:16:04 INFO - "basedir": "/builds/slave/test", 03:16:04 INFO - "buildnumber": 338, 03:16:04 INFO - "slavename": "talos-linux64-ix-048", 03:16:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:16:04 INFO - "platform": "ubuntu64_hw", 03:16:04 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:16:04 INFO - "branch": "mozilla-aurora", 03:16:04 INFO - "repo_path": "releases/mozilla-aurora", 03:16:04 INFO - "revision": "c25ec152b6eff61053d6d040b923baf8716abb37", 03:16:04 INFO - "stage_platform": "linux64-pgo", 03:16:04 INFO - "builduid": "0d6d77990de04ab99b037c124c572879", 03:16:04 INFO - "slavebuilddir": "test" 03:16:04 INFO - } 03:16:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:16:04 INFO - Running post-action listener: _resource_record_post_action 03:16:04 INFO - ##### 03:16:04 INFO - ##### Finished read-buildbot-config step (success) 03:16:04 INFO - ##### 03:16:04 INFO - ##### 03:16:04 INFO - ##### Running download-and-extract step. 03:16:04 INFO - ##### 03:16:04 INFO - Running pre-action listener: _resource_record_pre_action 03:16:04 INFO - Running main action method: download_and_extract 03:16:04 INFO - mkdir: /builds/slave/test/build/tests 03:16: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')]} 03:16:04 INFO - https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:16:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:16:05 INFO - Downloaded 1361 bytes. 03:16:05 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:05 INFO - Using the following test package requirements: 03:16:05 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:16:05 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:16:05 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:16:05 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'jsshell-linux-x86_64.zip'], 03:16:05 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:16:05 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:16:05 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:16:05 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:16:05 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:16:05 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:16:05 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:16:05 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')]} 03:16:05 INFO - https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:16:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:16:07 INFO - Downloaded 24526305 bytes. 03:16:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:16:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:16:08 INFO - Return code: 0 03:16:08 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:16:08 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')]} 03:16:08 INFO - https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:16:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:16:13 INFO - Downloaded 24526305 bytes. 03:16:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:16:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:16:15 INFO - Return code: 0 03:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:16:15 INFO - https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:16:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:16:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:16:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:16:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:16:16 INFO - Downloaded 10833369 bytes. 03:16:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:16:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:16:16 INFO - Return code: 0 03:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:16 INFO - https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:16:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:16:21 INFO - Downloaded 63623638 bytes. 03:16:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:21 INFO - mkdir: /builds/slave/test/properties 03:16:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:16:21 INFO - Writing to file /builds/slave/test/properties/build_url 03:16:21 INFO - Contents: 03:16:21 INFO - build_url:https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:22 INFO - Running post-action listener: _resource_record_post_action 03:16:22 INFO - Running post-action listener: set_extra_try_arguments 03:16:22 INFO - ##### 03:16:22 INFO - ##### Finished download-and-extract step (success) 03:16:22 INFO - ##### 03:16:22 INFO - ##### 03:16:22 INFO - ##### Running populate-webroot step. 03:16:22 INFO - ##### 03:16:22 INFO - Running pre-action listener: _resource_record_pre_action 03:16:22 INFO - Running main action method: populate_webroot 03:16:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:16:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:16:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:16:22 INFO - '--tppagecycles', 03:16:22 INFO - '7'], 03:16:22 INFO - 'tests': ['tsvgm']}}, 03:16:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:16:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:16:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:16:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:16:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['damp', 'tps']}, 03:16:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'tests': ['damp', 'tps']}, 03:16:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['dromaeo_dom']}, 03:16:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:16:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['a11yr', 03:16:22 INFO - 'ts_paint', 03:16:22 INFO - 'tpaint', 03:16:22 INFO - 'sessionrestore', 03:16:22 INFO - 'sessionrestore_no_auto_restore', 03:16:22 INFO - 'tabpaint']}, 03:16:22 INFO - 'other-e10s': {'tests': ['a11yr', 03:16:22 INFO - 'ts_paint', 03:16:22 INFO - 'tpaint', 03:16:22 INFO - 'sessionrestore', 03:16:22 INFO - 'sessionrestore_no_auto_restore', 03:16:22 INFO - 'tabpaint']}, 03:16:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:16:22 INFO - 'ts_paint', 03:16:22 INFO - 'tpaint', 03:16:22 INFO - 'sessionrestore', 03:16:22 INFO - 'sessionrestore_no_auto_restore', 03:16:22 INFO - 'tabpaint']}, 03:16:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:16:22 INFO - 'ts_paint', 03:16:22 INFO - 'tpaint', 03:16:22 INFO - 'sessionrestore', 03:16:22 INFO - 'sessionrestore_no_auto_restore', 03:16:22 INFO - 'tabpaint']}, 03:16:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['a11yr', 03:16:22 INFO - 'ts_paint', 03:16:22 INFO - 'tpaint', 03:16:22 INFO - 'sessionrestore', 03:16:22 INFO - 'sessionrestore_no_auto_restore', 03:16:22 INFO - 'tabpaint']}, 03:16:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['a11yr', 03:16:22 INFO - 'ts_paint', 03:16:22 INFO - 'tpaint', 03:16:22 INFO - 'sessionrestore', 03:16:22 INFO - 'sessionrestore_no_auto_restore', 03:16:22 INFO - 'tabpaint']}, 03:16:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['tsvgx', 03:16:22 INFO - 'tsvgr_opacity', 03:16:22 INFO - 'tart', 03:16:22 INFO - 'tscrollx', 03:16:22 INFO - 'cart']}, 03:16:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:16:22 INFO - 'tsvgr_opacity', 03:16:22 INFO - 'tart', 03:16:22 INFO - 'tscrollx', 03:16:22 INFO - 'cart']}, 03:16:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'talos_options': ['--disable-e10s'], 03:16:22 INFO - 'tests': ['tp5o']}, 03:16:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'tests': ['tp5o']}, 03:16:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'talos_options': ['--disable-e10s', 03:16:22 INFO - '--xperf_path', 03:16:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:22 INFO - 'tests': ['tp5n']}, 03:16:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:22 INFO - 'talos_options': ['--xperf_path', 03:16:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:22 INFO - 'tests': ['tp5n']}}, 03:16:22 INFO - 'talos.zip': {'path': '', 03:16:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:16:22 INFO - Running post-action listener: _resource_record_post_action 03:16:22 INFO - ##### 03:16:22 INFO - ##### Finished populate-webroot step (success) 03:16:22 INFO - ##### 03:16:22 INFO - ##### 03:16:22 INFO - ##### Running create-virtualenv step. 03:16:22 INFO - ##### 03:16:22 INFO - Running pre-action listener: _resource_record_pre_action 03:16:22 INFO - Running main action method: create_virtualenv 03:16:22 INFO - Creating virtualenv /builds/slave/test/build/venv 03:16:22 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 03:16:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:16:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:16:22 INFO - Using real prefix '/usr' 03:16:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:16:22 INFO - Installing distribute.............................................................................................................................................................................................done. 03:16:24 INFO - Installing pip.................done. 03:16:24 INFO - Return code: 0 03:16:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:24 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:24 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 03:16:24 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 03:16:24 INFO - Using env: {'DISPLAY': ':0', 03:16:24 INFO - 'HOME': '/home/cltbld', 03:16:24 INFO - 'LANG': 'en_US.UTF-8', 03:16:24 INFO - 'LANGUAGE': 'en_US:en', 03:16:24 INFO - 'LOGNAME': 'cltbld', 03:16:24 INFO - 'MAIL': '/var/mail/cltbld', 03:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:24 INFO - 'MOZ_NO_REMOTE': '1', 03:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:24 INFO - 'NO_EM_RESTART': '1', 03:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:24 INFO - 'PWD': '/builds/slave/test', 03:16:24 INFO - 'SHELL': '/bin/bash', 03:16:24 INFO - 'SHLVL': '1', 03:16:24 INFO - 'TERM': 'linux', 03:16:24 INFO - 'TMOUT': '86400', 03:16:24 INFO - 'USER': 'cltbld', 03:16:24 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:24 INFO - '_': '/tools/buildbot/bin/python'} 03:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:25 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 03:16:25 INFO - Cleaning up... 03:16:25 INFO - Return code: 0 03:16:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:16:25 INFO - Using env: {'DISPLAY': ':0', 03:16:25 INFO - 'HOME': '/home/cltbld', 03:16:25 INFO - 'LANG': 'en_US.UTF-8', 03:16:25 INFO - 'LANGUAGE': 'en_US:en', 03:16:25 INFO - 'LOGNAME': 'cltbld', 03:16:25 INFO - 'MAIL': '/var/mail/cltbld', 03:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:25 INFO - 'MOZ_NO_REMOTE': '1', 03:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:25 INFO - 'NO_EM_RESTART': '1', 03:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:25 INFO - 'PWD': '/builds/slave/test', 03:16:25 INFO - 'SHELL': '/bin/bash', 03:16:25 INFO - 'SHLVL': '1', 03:16:25 INFO - 'TERM': 'linux', 03:16:25 INFO - 'TMOUT': '86400', 03:16:25 INFO - 'USER': 'cltbld', 03:16:25 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:25 INFO - '_': '/tools/buildbot/bin/python'} 03:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:25 INFO - Downloading/unpacking psutil>=3.1.1 03:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:27 INFO - Installing collected packages: psutil 03:16:27 INFO - Running setup.py install for psutil 03:16:27 INFO - building 'psutil._psutil_linux' extension 03:16:27 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 03:16:27 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 03:16:27 INFO - building 'psutil._psutil_posix' extension 03:16:27 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 03:16:27 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 03:16:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:27 INFO - Successfully installed psutil 03:16:27 INFO - Cleaning up... 03:16:27 INFO - Return code: 0 03:16:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:27 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:27 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 03:16:27 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 03:16:27 INFO - Using env: {'DISPLAY': ':0', 03:16:27 INFO - 'HOME': '/home/cltbld', 03:16:27 INFO - 'LANG': 'en_US.UTF-8', 03:16:27 INFO - 'LANGUAGE': 'en_US:en', 03:16:27 INFO - 'LOGNAME': 'cltbld', 03:16:27 INFO - 'MAIL': '/var/mail/cltbld', 03:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:27 INFO - 'MOZ_NO_REMOTE': '1', 03:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:27 INFO - 'NO_EM_RESTART': '1', 03:16:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:27 INFO - 'PWD': '/builds/slave/test', 03:16:27 INFO - 'SHELL': '/bin/bash', 03:16:27 INFO - 'SHLVL': '1', 03:16:27 INFO - 'TERM': 'linux', 03:16:27 INFO - 'TMOUT': '86400', 03:16:27 INFO - 'USER': 'cltbld', 03:16:27 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:27 INFO - '_': '/tools/buildbot/bin/python'} 03:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:16:30 INFO - Installing collected packages: mozsystemmonitor 03:16:30 INFO - Running setup.py install for mozsystemmonitor 03:16:30 INFO - Successfully installed mozsystemmonitor 03:16:30 INFO - Cleaning up... 03:16:30 INFO - Return code: 0 03:16:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:30 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:30 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 03:16:30 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 03:16:30 INFO - Using env: {'DISPLAY': ':0', 03:16:30 INFO - 'HOME': '/home/cltbld', 03:16:30 INFO - 'LANG': 'en_US.UTF-8', 03:16:30 INFO - 'LANGUAGE': 'en_US:en', 03:16:30 INFO - 'LOGNAME': 'cltbld', 03:16:30 INFO - 'MAIL': '/var/mail/cltbld', 03:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:30 INFO - 'MOZ_NO_REMOTE': '1', 03:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:30 INFO - 'NO_EM_RESTART': '1', 03:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:30 INFO - 'PWD': '/builds/slave/test', 03:16:30 INFO - 'SHELL': '/bin/bash', 03:16:30 INFO - 'SHLVL': '1', 03:16:30 INFO - 'TERM': 'linux', 03:16:30 INFO - 'TMOUT': '86400', 03:16:30 INFO - 'USER': 'cltbld', 03:16:30 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:30 INFO - '_': '/tools/buildbot/bin/python'} 03:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:30 INFO - Downloading/unpacking jsonschema==2.5.1 03:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:16:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 INFO - Downloading functools32-3.2.3-2.tar.gz 03:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:16:32 INFO - warning: no files found matching '*.txt' 03:16:32 INFO - no previously-included directories found matching 'build' 03:16:32 INFO - no previously-included directories found matching 'dist' 03:16:32 INFO - no previously-included directories found matching '.git*' 03:16:32 INFO - Installing collected packages: jsonschema, functools32 03:16:32 INFO - Running setup.py install for functools32 03:16:32 INFO - warning: no files found matching '*.txt' 03:16:32 INFO - no previously-included directories found matching 'build' 03:16:32 INFO - no previously-included directories found matching 'dist' 03:16:32 INFO - no previously-included directories found matching '.git*' 03:16:32 INFO - Successfully installed jsonschema functools32 03:16:32 INFO - Cleaning up... 03:16:32 INFO - Return code: 0 03:16:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:16:32 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')]} 03:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:32 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')]} 03:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:32 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:32 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 03:16:32 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 03:16:32 INFO - Using env: {'DISPLAY': ':0', 03:16:32 INFO - 'HOME': '/home/cltbld', 03:16:32 INFO - 'LANG': 'en_US.UTF-8', 03:16:32 INFO - 'LANGUAGE': 'en_US:en', 03:16:32 INFO - 'LOGNAME': 'cltbld', 03:16:32 INFO - 'MAIL': '/var/mail/cltbld', 03:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:32 INFO - 'MOZ_NO_REMOTE': '1', 03:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:32 INFO - 'NO_EM_RESTART': '1', 03:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:32 INFO - 'PWD': '/builds/slave/test', 03:16:32 INFO - 'SHELL': '/bin/bash', 03:16:32 INFO - 'SHLVL': '1', 03:16:32 INFO - 'TERM': 'linux', 03:16:32 INFO - 'TMOUT': '86400', 03:16:32 INFO - 'USER': 'cltbld', 03:16:32 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:32 INFO - '_': '/tools/buildbot/bin/python'} 03:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:32 INFO - Downloading/unpacking blobuploader==1.2.4 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 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 03:16:34 INFO - Downloading blobuploader-1.2.4.tar.gz 03:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:16:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:16:34 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 03:16:34 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 03:16:34 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 03:16:34 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 03:16:34 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 03:16:34 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 03:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:16:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:16:35 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 03:16:35 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 03:16:35 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 03:16:35 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 03:16:35 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 03:16:35 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 03:16:35 INFO - Downloading docopt-0.6.1.tar.gz 03:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:16:35 INFO - Installing collected packages: blobuploader, requests, docopt 03:16:35 INFO - Running setup.py install for blobuploader 03:16:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:16:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:16:35 INFO - Running setup.py install for requests 03:16:36 INFO - Running setup.py install for docopt 03:16:36 INFO - Successfully installed blobuploader requests docopt 03:16:36 INFO - Cleaning up... 03:16:36 INFO - Return code: 0 03:16:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16: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')]} 03:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16: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')]} 03:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:36 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:36 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 03:16:36 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 03:16:36 INFO - Using env: {'DISPLAY': ':0', 03:16:36 INFO - 'HOME': '/home/cltbld', 03:16:36 INFO - 'LANG': 'en_US.UTF-8', 03:16:36 INFO - 'LANGUAGE': 'en_US:en', 03:16:36 INFO - 'LOGNAME': 'cltbld', 03:16:36 INFO - 'MAIL': '/var/mail/cltbld', 03:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:36 INFO - 'MOZ_NO_REMOTE': '1', 03:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:36 INFO - 'NO_EM_RESTART': '1', 03:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:36 INFO - 'PWD': '/builds/slave/test', 03:16:36 INFO - 'SHELL': '/bin/bash', 03:16:36 INFO - 'SHLVL': '1', 03:16:36 INFO - 'TERM': 'linux', 03:16:36 INFO - 'TMOUT': '86400', 03:16:36 INFO - 'USER': 'cltbld', 03:16:36 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:36 INFO - '_': '/tools/buildbot/bin/python'} 03:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:36 INFO - Running setup.py (path:/tmp/pip-kmYTRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:36 INFO - Running setup.py (path:/tmp/pip-7bm3i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:36 INFO - Running setup.py (path:/tmp/pip-75Tfw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:36 INFO - Running setup.py (path:/tmp/pip-BuNSAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:36 INFO - Running setup.py (path:/tmp/pip-6VW7AO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:36 INFO - Running setup.py (path:/tmp/pip-8HRUX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:36 INFO - Running setup.py (path:/tmp/pip-5_hlto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:36 INFO - Running setup.py (path:/tmp/pip-Z5X6LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:37 INFO - Running setup.py (path:/tmp/pip-VPCTcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:37 INFO - Running setup.py (path:/tmp/pip-fpNOcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:37 INFO - Running setup.py (path:/tmp/pip-QXJBjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:37 INFO - Running setup.py (path:/tmp/pip-RClaOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:37 INFO - Running setup.py (path:/tmp/pip-lgpUOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:37 INFO - Running setup.py (path:/tmp/pip-rSsvmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:37 INFO - Running setup.py (path:/tmp/pip-8Ieaps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:37 INFO - Running setup.py (path:/tmp/pip-hsHRCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:37 INFO - Running setup.py (path:/tmp/pip-ZvKWE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:16:37 INFO - Running setup.py install for manifestparser 03:16:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:16:37 INFO - Running setup.py install for mozcrash 03:16:37 INFO - Running setup.py install for mozdebug 03:16:37 INFO - Running setup.py install for mozdevice 03:16:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Running setup.py install for mozfile 03:16:38 INFO - Running setup.py install for mozhttpd 03:16:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Running setup.py install for mozinfo 03:16:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Running setup.py install for mozInstall 03:16:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Running setup.py install for mozleak 03:16:38 INFO - Running setup.py install for mozlog 03:16:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Running setup.py install for moznetwork 03:16:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:16:38 INFO - Running setup.py install for mozprocess 03:16:38 INFO - Running setup.py install for mozprofile 03:16:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:16:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:16:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:16:39 INFO - Running setup.py install for mozrunner 03:16:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:16:39 INFO - Running setup.py install for mozscreenshot 03:16:39 INFO - Running setup.py install for moztest 03:16:39 INFO - Running setup.py install for mozversion 03:16:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:16:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:16:39 INFO - Cleaning up... 03:16:39 INFO - Return code: 0 03:16:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16: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')]} 03:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16: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')]} 03:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:39 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:39 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 03:16:39 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 03:16:39 INFO - Using env: {'DISPLAY': ':0', 03:16:39 INFO - 'HOME': '/home/cltbld', 03:16:39 INFO - 'LANG': 'en_US.UTF-8', 03:16:39 INFO - 'LANGUAGE': 'en_US:en', 03:16:39 INFO - 'LOGNAME': 'cltbld', 03:16:39 INFO - 'MAIL': '/var/mail/cltbld', 03:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:39 INFO - 'MOZ_NO_REMOTE': '1', 03:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:39 INFO - 'NO_EM_RESTART': '1', 03:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:39 INFO - 'PWD': '/builds/slave/test', 03:16:39 INFO - 'SHELL': '/bin/bash', 03:16:39 INFO - 'SHLVL': '1', 03:16:39 INFO - 'TERM': 'linux', 03:16:39 INFO - 'TMOUT': '86400', 03:16:39 INFO - 'USER': 'cltbld', 03:16:39 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:39 INFO - '_': '/tools/buildbot/bin/python'} 03:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:39 INFO - Running setup.py (path:/tmp/pip-6U0LNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:39 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)) 03:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:39 INFO - Running setup.py (path:/tmp/pip-6junc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:39 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)) 03:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:39 INFO - Running setup.py (path:/tmp/pip-tSNWEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:39 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)) 03:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:39 INFO - Running setup.py (path:/tmp/pip-nK1Jg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:39 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)) 03:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:39 INFO - Running setup.py (path:/tmp/pip-SZxxIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:40 INFO - Running setup.py (path:/tmp/pip-u0kbyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:40 INFO - Running setup.py (path:/tmp/pip-W3mUDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:40 INFO - Running setup.py (path:/tmp/pip-nCKAuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:40 INFO - Running setup.py (path:/tmp/pip-yL_PcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:40 INFO - Running setup.py (path:/tmp/pip-IKU6xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:40 INFO - Running setup.py (path:/tmp/pip-Er1SFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:40 INFO - Running setup.py (path:/tmp/pip-a4Yf6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:40 INFO - Running setup.py (path:/tmp/pip-eHydeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:40 INFO - Running setup.py (path:/tmp/pip-TQWxzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:40 INFO - Running setup.py (path:/tmp/pip-495hfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:40 INFO - Running setup.py (path:/tmp/pip-b54aus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:40 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)) 03:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:40 INFO - Running setup.py (path:/tmp/pip-JdN2rY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:40 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)) 03:16:40 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)) 03:16:40 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)) 03:16:40 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)) 03:16:40 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)) 03:16:40 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)) 03:16:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:16:40 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 03:16:40 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 03:16:40 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 03:16:40 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 03:16:40 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 03:16:40 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 03:16:42 INFO - Downloading blessings-1.6.tar.gz 03:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:16:42 INFO - Installing collected packages: blessings 03:16:42 INFO - Running setup.py install for blessings 03:16:43 INFO - Successfully installed blessings 03:16:43 INFO - Cleaning up... 03:16:43 INFO - Return code: 0 03:16:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:16:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:43 INFO - Reading from file tmpfile_stdout 03:16:43 INFO - Current package versions: 03:16:43 INFO - argparse == 1.2.1 03:16:43 INFO - blessings == 1.6 03:16:43 INFO - blobuploader == 1.2.4 03:16:43 INFO - docopt == 0.6.1 03:16:43 INFO - functools32 == 3.2.3-2 03:16:43 INFO - jsonschema == 2.5.1 03:16:43 INFO - manifestparser == 1.1 03:16:43 INFO - mozInstall == 1.12 03:16:43 INFO - mozcrash == 0.17 03:16:43 INFO - mozdebug == 0.1 03:16:43 INFO - mozdevice == 0.48 03:16:43 INFO - mozfile == 1.2 03:16:43 INFO - mozhttpd == 0.7 03:16:43 INFO - mozinfo == 0.9 03:16:43 INFO - mozleak == 0.1 03:16:43 INFO - mozlog == 3.2 03:16:43 INFO - moznetwork == 0.27 03:16:43 INFO - mozprocess == 0.22 03:16:43 INFO - mozprofile == 0.28 03:16:43 INFO - mozrunner == 6.11 03:16:43 INFO - mozscreenshot == 0.1 03:16:43 INFO - mozsystemmonitor == 0.3 03:16:43 INFO - moztest == 0.7 03:16:43 INFO - mozversion == 1.4 03:16:43 INFO - psutil == 3.1.1 03:16:43 INFO - requests == 1.2.3 03:16:43 INFO - wsgiref == 0.1.2 03:16:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:43 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 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:43 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 03:16:43 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 03:16:43 INFO - Using env: {'DISPLAY': ':0', 03:16:43 INFO - 'HOME': '/home/cltbld', 03:16:43 INFO - 'LANG': 'en_US.UTF-8', 03:16:43 INFO - 'LANGUAGE': 'en_US:en', 03:16:43 INFO - 'LOGNAME': 'cltbld', 03:16:43 INFO - 'MAIL': '/var/mail/cltbld', 03:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:43 INFO - 'MOZ_NO_REMOTE': '1', 03:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:43 INFO - 'NO_EM_RESTART': '1', 03:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:43 INFO - 'PWD': '/builds/slave/test', 03:16:43 INFO - 'SHELL': '/bin/bash', 03:16:43 INFO - 'SHLVL': '1', 03:16:43 INFO - 'TERM': 'linux', 03:16:43 INFO - 'TMOUT': '86400', 03:16:43 INFO - 'USER': 'cltbld', 03:16:43 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:43 INFO - '_': '/tools/buildbot/bin/python'} 03:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 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)) 03:16:43 INFO - Cleaning up... 03:16:43 INFO - Return code: 0 03:16:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:16:43 INFO - Using env: {'DISPLAY': ':0', 03:16:43 INFO - 'HOME': '/home/cltbld', 03:16:43 INFO - 'LANG': 'en_US.UTF-8', 03:16:43 INFO - 'LANGUAGE': 'en_US:en', 03:16:43 INFO - 'LOGNAME': 'cltbld', 03:16:43 INFO - 'MAIL': '/var/mail/cltbld', 03:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:43 INFO - 'MOZ_NO_REMOTE': '1', 03:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:43 INFO - 'NO_EM_RESTART': '1', 03:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:43 INFO - 'PWD': '/builds/slave/test', 03:16:43 INFO - 'SHELL': '/bin/bash', 03:16:43 INFO - 'SHLVL': '1', 03:16:43 INFO - 'TERM': 'linux', 03:16:43 INFO - 'TMOUT': '86400', 03:16:43 INFO - 'USER': 'cltbld', 03:16:43 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:43 INFO - '_': '/tools/buildbot/bin/python'} 03:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:43 INFO - Cleaning up... 03:16:43 INFO - Return code: 0 03:16:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb614c7af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeac350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, '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': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', '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 03:16:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:16:43 INFO - Using env: {'DISPLAY': ':0', 03:16:43 INFO - 'HOME': '/home/cltbld', 03:16:43 INFO - 'LANG': 'en_US.UTF-8', 03:16:43 INFO - 'LANGUAGE': 'en_US:en', 03:16:43 INFO - 'LOGNAME': 'cltbld', 03:16:43 INFO - 'MAIL': '/var/mail/cltbld', 03:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:43 INFO - 'MOZ_NO_REMOTE': '1', 03:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:43 INFO - 'NO_EM_RESTART': '1', 03:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:43 INFO - 'PWD': '/builds/slave/test', 03:16:43 INFO - 'SHELL': '/bin/bash', 03:16:43 INFO - 'SHLVL': '1', 03:16:43 INFO - 'TERM': 'linux', 03:16:43 INFO - 'TMOUT': '86400', 03:16:43 INFO - 'USER': 'cltbld', 03:16:43 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:43 INFO - '_': '/tools/buildbot/bin/python'} 03:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:43 INFO - Downloading/unpacking flake8 03:16:43 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 03:16:43 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 03:16:43 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 03:16:43 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 03:16:43 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 03:16:43 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 03:16:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:16:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:16:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 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 03:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:16:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:16:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:16:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:16:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:16:46 INFO - no previously-included directories found matching 'docs/_build' 03:16:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:16:46 INFO - Running setup.py install for pep8 03:16:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:16:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:16:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:16:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:16:46 INFO - no previously-included directories found matching 'docs/_build' 03:16:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:16:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:16:46 INFO - Cleaning up... 03:16:46 INFO - Return code: 0 03:16:46 INFO - Running post-action listener: _resource_record_post_action 03:16:46 INFO - Running post-action listener: _start_resource_monitoring 03:16:46 INFO - Starting resource monitoring. 03:16:46 INFO - ##### 03:16:46 INFO - ##### Finished create-virtualenv step (success) 03:16:46 INFO - ##### 03:16:46 INFO - ##### 03:16:46 INFO - ##### Running install step. 03:16:46 INFO - ##### 03:16:46 INFO - Running pre-action listener: _resource_record_pre_action 03:16:46 INFO - Running main action method: install 03:16:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:47 INFO - Reading from file tmpfile_stdout 03:16:47 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:47 INFO - Reading from file tmpfile_stdout 03:16:47 INFO - Output received: 03:16:47 INFO - Usage: mozinstall [options] installer 03:16:47 INFO - Options: 03:16:47 INFO - -h, --help show this help message and exit 03:16:47 INFO - -d DEST, --destination=DEST 03:16:47 INFO - Directory to install application into. [default: 03:16:47 INFO - "/builds/slave/test"] 03:16:47 INFO - --app=APP Application being installed. [default: firefox] 03:16:47 INFO - mkdir: /builds/slave/test/build/application 03:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:17:02 INFO - Reading from file tmpfile_stdout 03:17:02 INFO - Output received: 03:17:02 INFO - /builds/slave/test/build/application/firefox/firefox 03:17:02 INFO - Running post-action listener: _resource_record_post_action 03:17:02 INFO - ##### 03:17:02 INFO - ##### Finished install step (success) 03:17:02 INFO - ##### 03:17:02 INFO - ##### 03:17:02 INFO - ##### Running run-tests step. 03:17:02 INFO - ##### 03:17:02 INFO - Running pre-action listener: _resource_record_pre_action 03:17:02 INFO - Running main action method: run_tests 03:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:17:02 INFO - Python 2.7.3 03:17:02 INFO - Return code: 0 03:17:02 INFO - grabbing minidump binary from tooltool 03:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:02 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 0xf3b1c0>, '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 0xf3c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3c550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:17:02 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 03:17:03 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 03:17:03 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 03:17:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:17:03 INFO - Return code: 0 03:17:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:17:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:17:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:17:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:17:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:17:03 INFO - Return code: 0 03:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 03:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 03:17:03 INFO - Using env: {'DISPLAY': ':0', 03:17:03 INFO - 'HOME': '/home/cltbld', 03:17:03 INFO - 'LANG': 'en_US.UTF-8', 03:17:03 INFO - 'LANGUAGE': 'en_US:en', 03:17:03 INFO - 'LOGNAME': 'cltbld', 03:17:03 INFO - 'MAIL': '/var/mail/cltbld', 03:17:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:03 INFO - 'MOZ_NO_REMOTE': '1', 03:17:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:03 INFO - 'NO_EM_RESTART': '1', 03:17:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:03 INFO - 'PWD': '/builds/slave/test', 03:17:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:03 INFO - 'SHELL': '/bin/bash', 03:17:03 INFO - 'SHLVL': '1', 03:17:03 INFO - 'TERM': 'linux', 03:17:03 INFO - 'TMOUT': '86400', 03:17:03 INFO - 'USER': 'cltbld', 03:17:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596', 03:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:03 INFO - '_': '/tools/buildbot/bin/python'} 03:17:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 03:17:04 INFO - mozversion application_buildid: 20160703003821 03:17:04 INFO - mozversion application_changeset: c25ec152b6eff61053d6d040b923baf8716abb37 03:17:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:17:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:04 INFO - mozversion application_name: Firefox 03:17:04 INFO - mozversion application_remotingname: firefox-dev 03:17:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:17:04 INFO - mozversion application_vendor: Mozilla 03:17:04 INFO - mozversion application_version: 49.0a2 03:17:04 INFO - mozversion platform_buildid: 20160703003821 03:17:04 INFO - mozversion platform_changeset: c25ec152b6eff61053d6d040b923baf8716abb37 03:17:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:17:04 INFO - mozversion platform_version: 49.0a2 03:17:04 INFO - using testdate: 1467541024 03:17:04 INFO - actual date: 1467541024 03:17:04 INFO - starting webserver on 'localhost:37288' 03:17:04 INFO - SUITE-START | Running 2 tests 03:17:04 INFO - TEST-START | dromaeo_css 03:17:04 INFO - operating with platform_type : linux_ 03:17:04 INFO - Initialising browser for dromaeo_css test... 03:17:04 INFO - TEST-INFO | started process 5437 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6Y8gY/profile http://localhost:37288/getInfo.html) 03:17:08 INFO - PROCESS | 5437 | __metrics Screen width/height:1600/1200 03:17:08 INFO - PROCESS | 5437 | colorDepth:24 03:17:08 INFO - PROCESS | 5437 | Browser inner width/height: 1024/697 03:17:08 INFO - PROCESS | 5437 | __metrics 03:17:09 ERROR - PROCESS | 5437 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:17:10 INFO - TEST-INFO | 5437: exit 0 03:17:10 INFO - Browser initialized. 03:17:10 INFO - Running cycle 1/1 for dromaeo_css test... 03:17:10 INFO - TEST-INFO | started process 5528 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6Y8gY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:22 INFO - PROCESS | 5528 | RSS: Main: 145625088 03:17:22 INFO - PROCESS | 5528 | 03:18:13 INFO - PROCESS | 5528 | 1467541093822 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] 03:20:22 INFO - PROCESS | 5528 | Cycle 1(1): loaded http://localhost:37288/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37288/tests/dromaeo/cssquery-ext.html) 03:20:23 INFO - PROCESS | 5528 | RSS: Main: 249688064 03:20:23 INFO - PROCESS | 5528 | 03:23:25 INFO - PROCESS | 5528 | Cycle 1(1): loaded http://localhost:37288/tests/dromaeo/cssquery-ext.html (next: http://localhost:37288/tests/dromaeo/cssquery-jquery.html) 03:23:26 INFO - PROCESS | 5528 | RSS: Main: 230076416 03:23:26 INFO - PROCESS | 5528 | 03:26:28 INFO - PROCESS | 5528 | Cycle 1(1): loaded http://localhost:37288/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37288/tests/dromaeo/cssquery-mootools.html) 03:26:28 INFO - PROCESS | 5528 | RSS: Main: 231358464 03:26:28 INFO - PROCESS | 5528 | 03:29:30 INFO - PROCESS | 5528 | Cycle 1(1): loaded http://localhost:37288/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37288/tests/dromaeo/cssquery-prototype.html) 03:29:31 INFO - PROCESS | 5528 | RSS: Main: 218202112 03:29:31 INFO - PROCESS | 5528 | 03:32:33 INFO - PROCESS | 5528 | Cycle 1(1): loaded http://localhost:37288/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37288/tests/dromaeo/cssquery-yui.html) 03:32:34 INFO - PROCESS | 5528 | RSS: Main: 195919872 03:32:34 INFO - PROCESS | 5528 | 03:35:36 INFO - PROCESS | 5528 | Cycle 1(1): loaded http://localhost:37288/tests/dromaeo/cssquery-yui.html (next: http://localhost:37288/tests/dromaeo/cssquery-dojo.html) 03:35:37 INFO - PROCESS | 5528 | RSS: Main: 259510272 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | __start_tp_report 03:35:37 INFO - PROCESS | 5528 | _x_x_mozilla_page_load 03:35:37 INFO - PROCESS | 5528 | _x_x_mozilla_page_load_details 03:35:37 INFO - PROCESS | 5528 | |i|pagename|runs| 03:35:37 INFO - PROCESS | 5528 | |0;dojo.html;3344.8327583620817;3389.1357703785548;3396.269801992571;3415.992282276728;3438.9656103438974;5564.276644036275;5633.070543360346;5657.745401456934;5681.517071048966;5682.6306290091115;5565.9443405565935;5615.7472913718875;5626.409227031164;5629.521490673298;5637.238972738679;4816.710997340153;4967.776450059738;5081.212412076134;5082.339295891529;5115.335006449156;4983.277424773406;5019.870529130934;5031.672941258825;5035.723035233061;5036;5011.473795251482;5048.318477005612;5074.84775456735;5135.794568217267;5162.22566615007;5195.506426889439;5217.243499692523;5218.556422704075;5218.947810521884;5245.554127899114;4910.042541704369;5010.674306170124;5047.87380315495;5124.410692770304;5136.563392111675;5331.040412725708;5400.378956420022;5402.297701298845;5415.214793854869;5445.401005889349;5970.313413957407;6014.3985601439945;6019.217501724792;6027.758889644409;6038.3659715729655;5969.522438204933;5992.310884248323;6000.399960004008;6072.574919755619;6096.7561297548045;5624.71876406178;5649.548036157097;5664.745086471119;5675.716214189274;5679.488846003838;5894.027485464936;6159.076138579159;6166.907496387557;6171.352008039091;6171.814845554641;5788.53691704671;5813.331466881278;5826.504747096459;5935.080062590306;5935.317438494542;5972.462478376967;5984.401559844068;5991.071383935497;6020.187274717945;6021.7591296347655;5723.885522289531;5957.344692083867;5965.463108320185;5965.492933100648;5966.075258334965;6008.969955150196;6087.269527656621;6091.329953705089;6094.878102437927;6138.14066030756;4674.392328997209;4679.508651591602;4684.765761711901;4686.039361930796;4712.882177945578;4038.0712436139265;4098.159877225162;4151.958480415157;4180.226658068267;4203.894902627459;5857.004309267435;5857.88284234313;5860.589758716849;5864.38423965486;5890.793822216202;6117.974595441065;6134.478569321657;6136.7852125175405;6138.2121944883065;6145.477634401126;5616.185653080281;5682.204491371212;5722;5730.828075157752;5846.6784326862435;5147.356580427447;5158.535731784157;5162.199859021783;5163.638545301793;5188.169892817131;6331.810045698637;6341.143945567289;6342;6342.175517182829;6342.334054924258;5843.678597677169;6000.339962604023;6022.5784195105925;6040.516758659384;6052.969735151296;6282.8429289266305;6288.622682638873;6337.144485494584;6338.461230795249;6339.049142628458;5899.115132730125;5920.822375328747;5928.199693041384;5933.169356290036;5973.283206015132;5792.797252096157;5887.470127688529;5922.230110085834;5933.258342707161;6019.638821670714;5860.531157507475;5860.853478663067;5863.794767183128;5864.442877926761;5868.735906884286;5772.393898640582;5809.622374545641;5810.215620891295;5817.970910145422;5819.82540523785;5584.246126772834;5666.405027472221;5669.064604340402;5706.258186435737;5754.53963682896;5875.794347197828;5887.20522729426;5921.082232254007;5927.8221653350465;5929.377415371497;5547.5007249347755;5758.222639943721;5767.942320576741;5770.6249093808765;5785 03:35:37 INFO - PROCESS | 5528 | |1;ext.html;595.44920948123;638.1671918146818;645.4900628503481;652.9085927970082;667.5560752099854;33730.83134584327;46306.53693463065;47434.28848567274;47437.76281118598;47523.52476475239;49293.26060109101;49498.25752613714;49678;49792.50207497924;49887;8514.48913065218;8753.3872628916;9137.131972462623;9299.16307532322;10917.563297468092;10700.411477368742;11129;11134.27627204229;11138.053265472445;11139.665810025712;11204.047655949254;11242.213045086806;11245.437728113562;11255;11255.099592032659;8481.66073357065;10936.179786516042;11105.444727763619;11154.274972126866;11222.831657525143;10621;11023.559057637685;11040.33757974524;11040.33757974524;11051.336919784839;45660.315095273596;46092.53907460949;46187;46197;46225.53774462246;3885.067583779901;3893.824777885002;3923.234969181011;3941.625545573166;4331.090471001094;35014;35389.823050884836;35433.82283088594;35682.64317356845;37323.44014839779;4644.674872758908;4672.322513235596;4703.764811759433;4712.31671407644;4801.855944321641;386446;389166;389372;391213;391461;38524.614753852664;39736.20527589432;71218.64390678117;72292.63853680804;72295.63852180705;40932.59067409288;41508.58491415108;41596;41637.79181104136;41664;16644.916775416288;16715.247813848408;16759;16768.664626707643;16772.329106835834;13890.09714368583;14860.62848428798;14885.851141488447;14953.850461495464;15008.174550399834;9443.386179898285;9513.571889265;9531.857022144673;9581.952090239503;9595.568199431042;3582.1044738815294;3649.069487280726;3670.541182352206;3671.430928206132;3741.0460332615007;1097.8682558092921;1138.960101550635;1141.7431078007382;1143.1255089856268;1167.6146871532374;34785.13037174091;35285.29429411398;35379.292414152085;35469.82265088658;35503.82248088743;1183.5206741269799;1183.9408029598485;1204.9819252711216;1230.3233221728015;1263.8988880889506;17635.29458821633;18277.451676449728;18469.630607388044;18532.81467185338;18558.257669693332;593892;594999;596114;596133;602121;65121.67439162869;66146.66926665336;66148;66170;66300.33699663039;1393.1989106263736;1436.7126574685315;1454.8472410396967;1498.8276348219877;1504.8344682084962;34405;35784.821075894455;36073.278534429686;36114;36396.27207455889;56602;57646.13530796958;57870.131948019975;57944.420555793906;58027;447691;450313;450647;451303;451514;28047.859760701067;29922.700772992863;30955.226119347197;31138.688613114486;32808;52177.47822521726;53245.4675453256;53956.73021634867;54004;54066.459335407715;44215.33676994912;44762.776186120165;44784.552154478035;45612.543874560826;46429;44224.11551769011;44261.557384425745;44289.557104428546;44448;45015.324770127816;44344;44400.333994990746;44600.33099503575;44708.55291447174;46574.767126165505;39126.6087339123;40564.1887162261;41160.38259426051;41212.38181427341;41271.380929285486;30447.238819029702;30586.235344116576;30674.00480260155;30865;31079.378412432077 03:35:37 INFO - PROCESS | 5528 | |2;jquery.html;420.12404137373585;429.5940336382116;435.35567360306743;438.37750394439894;442.65694087082505;18812.247510099598;19648.50878728031;20567.28014519492;20693.586128277424;20707.792922070774;17693;19265.807341926575;19450.416487505398;20208.696869546933;20229.39311820644;5353.437889021655;5544.667319960795;5568.832935011957;5578.86052848678;5588.21764952907;5430.56555475561;5430.647007944471;5463;5498.532624726883;5555.2778138841895;5435.565154787627;5505.256790333294;5535.750891209885;5546.833594992157;5567.916481252784;5453.918191227135;5456.972715136419;5497.45025497451;5860.97069514654;6169.444749972479;6034.396560343975;6052.394760523956;6054.273487181523;6077.817665469999;6078.696065196723;14954;15584.61038474036;21919;24381.14665986682;24461.633075503883;2230.542738738555;2332.183735692511;2514.748525147489;2556.8338058026357;2582.19951814938;10736.409497477636;10801.83797243042;10904;11235.325880447199;11523.654290371302;2037.847161462896;2046.72369230155;2149.7850214978534;2163.8052575268143;2293.908243670251;82714.58642706748;85672;86018.13981860164;88710.556447218;89328.55335723345;11408.201425900108;11665.300081995107;11737.178397512092;11784.646460606196;12065.215760975509;16693.91653041727;16749.330026799034;16976.066316352473;17061.7440738389;17074.658506829794;8916.331275154389;8974.41666291689;9067.954660226656;9152.725418237464;9206.677766278148;8906.643734250558;8926.553672316488;8948.910510894808;9001.639934402681;9036.728898133066;5163;5168.405633352213;5171.095058364846;5187.377514698261;5253.054450198924;2012.4063401296653;2013.1645367172753;2014.4963759060236;2045.9795402046086;2100.411884672294;954.398728800851;1025.4257615735212;1055.7360659835042;1106.8505751723415;1124.12318391653;19428.51428714265;19964.401067967985;20279.4930126748;20365.08357123933;20460.46546509018;1028.881678606955;1048.3028785857318;1066.6746642274181;1076.445630500293;1097.9890201097887;10554.47227638615;11040.613578524713;11155.498002589902;11166.385848778233;11200.887991120147;390383;393788.0931786026;397381;491643;513120;103709;107853;109438;111144;111539;1050.4642632257603;1090.825467925144;1119.412308538024;1128.2553514680271;1129.0290350298571;17654.558636034202;17736.02451865108;17882;18070;18113.36603218881;42013.78993105015;43485.3477197836;43662.12675746531;43929;44015.55984440115;62597.68701156465;63856.68071659612;64449.67775161094;64669;64961;8978.955105224431;9285.675001374919;9300.6279748812;9374.343795934492;9516.00081991381;11199.552017919192;13295.933520332723;13493.527726529524;13681;13685.657858553615;11810.822837657613;11891.821622675494;11915.940420297842;11965.76068478608;12315.815262770886;11482.36846973391;11557.768844623228;11611.187216894736;11781.352025638673;11876.346800925687;11860.94069529647;12013.219140755822;12250.75498490043;12435.813462798245;12646.114771965875;9093.863592045993;9151.947526034324;9275.39709918853;9326.766830829283;9452.905470945478;9116.954415227881;12003.339816310187;12252.632421027383;12359.35006096906;12555.93722031384 03:35:37 INFO - PROCESS | 5528 | |3;mootools.html;1413.3004162939333;1584.5642448326712;1601.4635097242424;1833.825786550278;1966.2331690640654;9033.367664263496;9316.813663726722;9417.670381536647;9704.466254356008;9707.271954603415;9442.575084121214;9650.08324209199;9650.469224192666;9654.42073475592;9661.710148695533;2228.342638921522;2481.081999660128;2529.215943057649;2558.5010922870047;2560.0527804712283;2562.1929092335854;2579.496998085374;2582.5093232285803;2584.987075064622;2588.948221035578;2511.3721569607596;2553.6041913503436;2554.2337298810376;2556.846589204654;2557.4757174779124;2107.230860735836;2357.2574638988663;2399.0763556030965;2455.8526488410575;2458.483718419134;2451.4851881104987;2454.901803927841;2473.641322008317;2476.6780318558654;2502.94994100117;9732.026797320283;9788.608455661764;9793;9801.11789938909;9804.705858824247;5774.769009239625;5919.112133180014;5922.14128951304;5923.5853490255695;5923.970380148115;3490.5462289902403;3606.278744251134;3612.8916132516065;3647.0335361129323;3654.3422184006868;1189.1913498820797;1209.9758004839941;1212.2060050666828;1216.4586758892297;1264.3109505319624;13863.23752193629;13932.233727145003;13937.442502299897;14017.64955876101;14056.71886562263;5103.515166059218;5176.171812509981;5237.423883372826;5264.631475796734;5316.122839731401;11609.419529023515;11684.18210725258;11687.123465740104;11693.889080537336;11703.414829258674;5941.22764040672;5944.524438044946;5947.672877991752;5954.583179177416;5958.374370691102;4701.177293973541;5279.894402112013;5411.080116380225;5431.185322201702;5522.061249587579;4367.5414081520985;4403.14138742942;4456.598906098467;4470.485894122153;4495.797059440916;1381.86181381861;1397.7484052870377;1404.1996062244423;1405.051590176647;1405.9367328470244;933.8225737109927;944.730751735752;951.9095685909828;1079.7894410589859;1083.3608171179042;6482.546221764431;6611.63636000015;6628.204615446178;6676.766313179112;6792;999.7850462150673;1011.8178727828914;1024.774549599087;1041.9687409377732;1061.9787604247872;4382.9342559861625;4420.204363214652;4460.375547423363;4466.553344665507;4559.293309537027;11871.643850684493;12203.633890983285;12219.083568732382;12227.877721222852;12232.816507752214;11596;11988.460519276654;12094;12115.39423028845;12200.206986546023;9003;9133;9152.267818574632;9204.493752843659;9206.63173473067;6676.265610782907;6702.932970670231;6766.221884483348;6809.285025072296;6875.4155896749335;10645.201609879136;11038.006579407891;11124.221304508606;11298.152638552307;11303.265287756269;11340.943295283801;11408.372539510407;11430.828537571777;11438.656840294805;11512;7485.0643669541305;8431.241188293083;8607.397482176188;8658.437201581877;8825.426347287406;9532;9627.085426884434;9631.71104866855;9660.565274562801;9668.951655241679;8033.55815430157;8200.097989221062;8242.670293188205;8361.916380836114;8366.414350995372;8191.754247372588;8261.297789687704;8349.332053435832;8396.244338009608;8493.065762765968;7165.176004759311;7518.473706840468;7791.571463569559;7798.415118865994;7966.760997170094;5606.523445507178;5660.490555849993;5685.857853553694;5861.677607731615;5865.296164460292;5688.658680479184;5710.914336285042;5741.081426971665;5875.941240587539;6071.241094863142 03:35:37 INFO - PROCESS | 5528 | |4;prototype.html;355.69765699155715;360.15363894847104;363.9053846000039;367.4414889368162;367.62318623411;5382.2195781611645;5454.863628409292;5465.754041068157;5478.150886612571;5507.889842203153;5341.492558206974;5402.486763757437;5436.972815135929;5445.767235433473;5459.672419654824;4107.822853887437;4231.4499115114995;4233.1322078973735;4238.9152216955645;4248.723832950848;4152.584741525839;4219.282721937263;4224.72539284947;4225.8520951766695;4244.660427165819;4220.36694495825;4231.322988321869;4235.385869048986;4240.215560121372;4262.126264115865;4156.771377574232;4214.651311580295;4215.87352379426;4221.670173895213;4268.637165840907;3714.1086139326635;3721.274351501458;3723.5345581802276;3726.869559565402;3743.644353786386;5179.197224430181;5184.974075129638;5203.791848326063;5206.895862082775;5217.765200565984;4670.673052886299;4760.5715485606115;4783.354247176621;4829.855104346875;4833.685810422311;5603.691796951166;5639.436056394369;5648.096304591287;5648.350439699446;5657.23627310312;4110.116324990141;4131.132462182945;4134.855280065183;4139.172165566899;4149.688773342012;5661.980843448177;5890.234269544974;5890.469857712784;5910.615809972338;5921.703914804286;4110.355857656961;4215;4233.30150525166;4240.936385954213;4245.235857545671;4283.271843786564;4287;4287.292596721569;4291.334843099325;4296.119295544406;4595.333676616872;4830.951690483121;4846.176150054499;4849.927251091236;4880.975595122001;5672.858178545569;5680.630759000652;5688.744006519716;5721.256236689203;5728.198052272685;1451.985480145185;1461.5103940179947;1464.3306630441305;1476.372541669786;1480.2154857925316;926.6339795780628;945.7021038372891;948.2793077261193;958.7459323279337;971.9271054670928;4893.266010098514;4924.482929292444;4961.362750292395;4963.776630051656;5043.218301163325;5148.253503242084;5153.819616313411;5167.379914410295;5184.662996905189;5213.6350455467755;4673.3690951721765;4993.626752643052;4997.401870653124;5024.618229986783;5026.246063090565;2698.838069715784;2730.5494593392264;2762.06089929425;2786.7770578353684;2798.8040837141207;5341.691285635045;5359.330083739533;5378.381486129067;5453.590980676386;5471.644343117685;5328.135152696525;5340.9704312361055;5559.583031272671;5745.971270143623;5799.739011744482;6150.323464418911;6172.166757487771;6173;6212.596181248114;6214.564980451415;5837.09525023622;5906.881862362815;5918.201042859159;5920.467157955804;5934.169216309634;5643.604947653625;5747.425257474386;5782.942170578241;5813.575673959906;5831.679257640871;6110.083487476736;6160.10678451631;6162.969185154046;6164.291106522807;6184.907226391518;4131.235721391552;4172.31156859127;4204.348326009474;4214.557471465575;4228.323468245066;5841.32824725162;5845.1232315153065;5845.912311315419;5870.853228669317;5880.470757631833;5767.7404516796005;5772.5959182856795;5786.508146807569;5799.362070172193;5807.738651760766;5387.946120538744;5638.4925356718095;5662.122371032497;5665.518430933417;5704.575555389026;5602.2717046783655;5629;5653.632513886749;5664.603477756517;5670.404607516316;5594.1049432092295;5595.636283641713;5730.656160630376;5760.683162426107;5826.912596310974;5585.441455854545;5618.522425593706;5626.887462250814;5627.8593035174445;5632.436756324499 03:35:37 INFO - PROCESS | 5528 | |5;yui.html;820.2289847543309;842.7640260726997;851.4082712514804;874.2874557235854;926.4024704065882;14836.55490335289;16826.91586542067;17458.12709364533;17519.211635476404;17524.299028038862;11770;15557.53327400177;15709.450169244079;15727.056376617411;15743.134127622976;865.3639574912451;889.6975028490313;893.6648756716231;896.2337201692557;896.6413434626137;862.7282406042077;864.749222725412;870.5342641686699;871.1636828644508;871.8866547348836;768.1511929318079;915.2769312243353;919.4850883505225;919.5034681272105;919.6965001549472;776.6427443376053;779.2441331908041;897.6723495924006;924.5931790012373;925.9398139120935;664.0205696597528;664.6610228783306;664.8504086580529;665.0556210181554;666.7699643622991;16143;16146.677066458606;16193.433229837017;16194.190290485547;16210.108444035573;14459.927700361432;14678.559643210721;14794.482193123189;14794.852051479455;14831.406743730346;13214.273214973173;13552.932235338858;13561.389737461739;13577.1853688779;13869.09850859691;4743.407074115736;4758.547937945889;4823.493533179036;4826.517348265181;4882.902341953141;70018.64990675065;72017.63991180062;77589;77789;78737.60631196864;12257.38713064361;12367.505299787908;12621.242725436503;12779.488820447265;12967;22896.198633047763;24740.25779226626;24811.13161039391;24883.253502394957;24924.626130608056;9568.856467152998;9570.904290957002;9639.71080867575;9666;9686.75783105428;4939.925901111486;4999.900499492527;5003;5051.444341122474;5157.613179011589;4012.217617564547;4119.855805046809;4123.8556650517085;4126.42230087788;4127.525334586561;664.1598378051768;672.3008071605573;672.8687905858409;680.119245576979;696.7561353526327;604.1873679900526;667.9064930909676;675.4360109308782;680.6392611915693;714.1751277274756;9408.200302974325;9465.810683786423;9558.235341172689;9618.086281803218;9904.504774761233;700.1702981966284;726.8364617960916;745.7315366468067;747;759.506320891425;4486.12520558488;4513.616342610849;4558.361829343895;4584.541545845389;4586.816527338936;70582.29417705757;105463.47268263763;110151;112735.43632281786;113745;61336.38663613396;61817;82404.58797705972;95765;95918;85059.57470212609;87035.56482217548;87158.56420717856;87533.5623321892;87721;8967.461952282623;9297.814043718952;9305.348625596143;9497.43015419077;9628.085331893459;48942.51057489379;52429.21356179737;53134.73432632812;53283.46716532785;54234.186487201936;70309;74330.25669743233;75132.24867751253;75568.62215688886;75728.62135689287;12644.557440489538;12808.615741527768;12919.418626162033;13210.339483026002;13327.267000315076;13937.58187254384;14170.787438188641;14172.362243698852;15084.245787710352;15131.773023404878;12220.64950858235;12383.69040773988;14729.55811325662;15380;15672.529824105295;14128.858711413166;14207.715845683235;14380.28098595045;14512.346944387738;15057.924710376377;14598.416063357347;14919.403223871357;15355.769663454834;15375.154366509949;15398.230088495306;11988.64034078979;12026.218295810744;12228.633141005785;12492.437840297027;12781.16922400041;12657.177283476545;12732.809007864704;12776.936115319364;13093.541726039542;13139.934300328438 03:35:37 INFO - PROCESS | 5528 | __end_tp_report 03:35:37 INFO - PROCESS | 5528 | __start_cc_report 03:35:37 INFO - PROCESS | 5528 | _x_x_mozilla_cycle_collect,822 03:35:37 INFO - PROCESS | 5528 | __end_cc_report 03:35:37 INFO - PROCESS | 5528 | __startTimestamp1467542137177__endTimestamp 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | ------- Summary: start ------- 03:35:37 INFO - PROCESS | 5528 | Number of tests: 6 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | [#0] dojo.html Cycles:165 Average:5584.45 Median:5792.80 stddev:623.40 (10.8%) stddev-sans-first:600.04 03:35:37 INFO - PROCESS | 5528 | Values: 3344.8 3389.1 3396.3 3416.0 3439.0 5564.3 5633.1 5657.7 5681.5 5682.6 5565.9 5615.7 5626.4 5629.5 5637.2 4816.7 4967.8 5081.2 5082.3 5115.3 4983.3 5019.9 5031.7 5035.7 5036.0 5011.5 5048.3 5074.8 5135.8 5162.2 5195.5 5217.2 5218.6 5218.9 5245.6 4910.0 5010.7 5047.9 5124.4 5136.6 5331.0 5400.4 5402.3 5415.2 5445.4 5970.3 6014.4 6019.2 6027.8 6038.4 5969.5 5992.3 6000.4 6072.6 6096.8 5624.7 5649.5 5664.7 5675.7 5679.5 5894.0 6159.1 6166.9 6171.4 6171.8 5788.5 5813.3 5826.5 5935.1 5935.3 5972.5 5984.4 5991.1 6020.2 6021.8 5723.9 5957.3 5965.5 5965.5 5966.1 6009.0 6087.3 6091.3 6094.9 6138.1 4674.4 4679.5 4684.8 4686.0 4712.9 4038.1 4098.2 4152.0 4180.2 4203.9 5857.0 5857.9 5860.6 5864.4 5890.8 6118.0 6134.5 6136.8 6138.2 6145.5 5616.2 5682.2 5722.0 5730.8 5846.7 5147.4 5158.5 5162.2 5163.6 5188.2 6331.8 6341.1 6342.0 6342.2 6342.3 5843.7 6000.3 6022.6 6040.5 6053.0 6282.8 6288.6 6337.1 6338.5 6339.0 5899.1 5920.8 5928.2 5933.2 5973.3 5792.8 5887.5 5922.2 5933.3 6019.6 5860.5 5860.9 5863.8 5864.4 5868.7 5772.4 5809.6 5810.2 5818.0 5819.8 5584.2 5666.4 5669.1 5706.3 5754.5 5875.8 5887.2 5921.1 5927.8 5929.4 5547.5 5758.2 5767.9 5770.6 5785.0 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | [#1] ext.html Cycles:180 Average:64627.45 Median:3932.43 stddev:129204.08 (3285.6%) stddev-sans-first:129477.08 03:35:37 INFO - PROCESS | 5528 | Values: 595.4 638.2 645.5 652.9 667.6 33730.8 46306.5 47434.3 47437.8 47523.5 49293.3 49498.3 49678.0 49792.5 49887.0 8514.5 8753.4 9137.1 9299.2 10917.6 10700.4 11129.0 11134.3 11138.1 11139.7 11204.0 11242.2 11245.4 11255.0 11255.1 8481.7 10936.2 11105.4 11154.3 11222.8 10621.0 11023.6 11040.3 11040.3 11051.3 45660.3 46092.5 46187.0 46197.0 46225.5 3885.1 3893.8 3923.2 3941.6 4331.1 35014.0 35389.8 35433.8 35682.6 37323.4 4644.7 4672.3 4703.8 4712.3 4801.9 386446.0 389166.0 389372.0 391213.0 391461.0 38524.6 39736.2 71218.6 72292.6 72295.6 40932.6 41508.6 41596.0 41637.8 41664.0 16644.9 16715.2 16759.0 16768.7 16772.3 13890.1 14860.6 14885.9 14953.9 15008.2 9443.4 9513.6 9531.9 9582.0 9595.6 3582.1 3649.1 3670.5 3671.4 3741.0 1097.9 1139.0 1141.7 1143.1 1167.6 34785.1 35285.3 35379.3 35469.8 35503.8 1183.5 1183.9 1205.0 1230.3 1263.9 17635.3 18277.5 18469.6 18532.8 18558.3 593892.0 594999.0 596114.0 596133.0 602121.0 65121.7 66146.7 66148.0 66170.0 66300.3 1393.2 1436.7 1454.8 1498.8 1504.8 34405.0 35784.8 36073.3 36114.0 36396.3 56602.0 57646.1 57870.1 57944.4 58027.0 447691.0 450313.0 450647.0 451303.0 451514.0 28047.9 29922.7 30955.2 31138.7 32808.0 52177.5 53245.5 53956.7 54004.0 54066.5 44215.3 44762.8 44784.6 45612.5 46429.0 44224.1 44261.6 44289.6 44448.0 45015.3 44344.0 44400.3 44600.3 44708.6 46574.8 39126.6 40564.2 41160.4 41212.4 41271.4 30447.2 30586.2 30674.0 30865.0 31079.4 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | [#2] jquery.html Cycles:180 Average:28593.05 Median:11404.10 stddev:73582.88 (645.2%) stddev-sans-first:73758.89 03:35:37 INFO - PROCESS | 5528 | Values: 420.1 429.6 435.4 438.4 442.7 18812.2 19648.5 20567.3 20693.6 20707.8 17693.0 19265.8 19450.4 20208.7 20229.4 5353.4 5544.7 5568.8 5578.9 5588.2 5430.6 5430.6 5463.0 5498.5 5555.3 5435.6 5505.3 5535.8 5546.8 5567.9 5453.9 5457.0 5497.5 5861.0 6169.4 6034.4 6052.4 6054.3 6077.8 6078.7 14954.0 15584.6 21919.0 24381.1 24461.6 2230.5 2332.2 2514.7 2556.8 2582.2 10736.4 10801.8 10904.0 11235.3 11523.7 2037.8 2046.7 2149.8 2163.8 2293.9 82714.6 85672.0 86018.1 88710.6 89328.6 11408.2 11665.3 11737.2 11784.6 12065.2 16693.9 16749.3 16976.1 17061.7 17074.7 8916.3 8974.4 9068.0 9152.7 9206.7 8906.6 8926.6 8948.9 9001.6 9036.7 5163.0 5168.4 5171.1 5187.4 5253.1 2012.4 2013.2 2014.5 2046.0 2100.4 954.4 1025.4 1055.7 1106.9 1124.1 19428.5 19964.4 20279.5 20365.1 20460.5 1028.9 1048.3 1066.7 1076.4 1098.0 10554.5 11040.6 11155.5 11166.4 11200.9 390383.0 393788.1 397381.0 491643.0 513120.0 103709.0 107853.0 109438.0 111144.0 111539.0 1050.5 1090.8 1119.4 1128.3 1129.0 17654.6 17736.0 17882.0 18070.0 18113.4 42013.8 43485.3 43662.1 43929.0 44015.6 62597.7 63856.7 64449.7 64669.0 64961.0 8979.0 9285.7 9300.6 9374.3 9516.0 11199.6 13295.9 13493.5 13681.0 13685.7 11810.8 11891.8 11915.9 11965.8 12315.8 11482.4 11557.8 11611.2 11781.4 11876.3 11860.9 12013.2 12250.8 12435.8 12646.1 9093.9 9151.9 9275.4 9326.8 9452.9 9117.0 12003.3 12252.6 12359.4 12555.9 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | [#3] mootools.html Cycles:180 Average:6418.22 Median:4630.24 stddev:3689.59 (79.7%) stddev-sans-first:3680.77 03:35:37 INFO - PROCESS | 5528 | Values: 1413.3 1584.6 1601.5 1833.8 1966.2 9033.4 9316.8 9417.7 9704.5 9707.3 9442.6 9650.1 9650.5 9654.4 9661.7 2228.3 2481.1 2529.2 2558.5 2560.1 2562.2 2579.5 2582.5 2585.0 2588.9 2511.4 2553.6 2554.2 2556.8 2557.5 2107.2 2357.3 2399.1 2455.9 2458.5 2451.5 2454.9 2473.6 2476.7 2502.9 9732.0 9788.6 9793.0 9801.1 9804.7 5774.8 5919.1 5922.1 5923.6 5924.0 3490.5 3606.3 3612.9 3647.0 3654.3 1189.2 1210.0 1212.2 1216.5 1264.3 13863.2 13932.2 13937.4 14017.6 14056.7 5103.5 5176.2 5237.4 5264.6 5316.1 11609.4 11684.2 11687.1 11693.9 11703.4 5941.2 5944.5 5947.7 5954.6 5958.4 4701.2 5279.9 5411.1 5431.2 5522.1 4367.5 4403.1 4456.6 4470.5 4495.8 1381.9 1397.7 1404.2 1405.1 1405.9 933.8 944.7 951.9 1079.8 1083.4 6482.5 6611.6 6628.2 6676.8 6792.0 999.8 1011.8 1024.8 1042.0 1062.0 4382.9 4420.2 4460.4 4466.6 4559.3 11871.6 12203.6 12219.1 12227.9 12232.8 11596.0 11988.5 12094.0 12115.4 12200.2 9003.0 9133.0 9152.3 9204.5 9206.6 6676.3 6702.9 6766.2 6809.3 6875.4 10645.2 11038.0 11124.2 11298.2 11303.3 11340.9 11408.4 11430.8 11438.7 11512.0 7485.1 8431.2 8607.4 8658.4 8825.4 9532.0 9627.1 9631.7 9660.6 9669.0 8033.6 8200.1 8242.7 8361.9 8366.4 8191.8 8261.3 8349.3 8396.2 8493.1 7165.2 7518.5 7791.6 7798.4 7966.8 5606.5 5660.5 5685.9 5861.7 5865.3 5688.7 5710.9 5741.1 5875.9 6071.2 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | [#4] prototype.html Cycles:180 Average:4723.91 Median:5334.55 stddev:1390.42 (26.1%) stddev-sans-first:1355.11 03:35:37 INFO - PROCESS | 5528 | Values: 355.7 360.2 363.9 367.4 367.6 5382.2 5454.9 5465.8 5478.2 5507.9 5341.5 5402.5 5437.0 5445.8 5459.7 4107.8 4231.4 4233.1 4238.9 4248.7 4152.6 4219.3 4224.7 4225.9 4244.7 4220.4 4231.3 4235.4 4240.2 4262.1 4156.8 4214.7 4215.9 4221.7 4268.6 3714.1 3721.3 3723.5 3726.9 3743.6 5179.2 5185.0 5203.8 5206.9 5217.8 4670.7 4760.6 4783.4 4829.9 4833.7 5603.7 5639.4 5648.1 5648.4 5657.2 4110.1 4131.1 4134.9 4139.2 4149.7 5662.0 5890.2 5890.5 5910.6 5921.7 4110.4 4215.0 4233.3 4240.9 4245.2 4283.3 4287.0 4287.3 4291.3 4296.1 4595.3 4831.0 4846.2 4849.9 4881.0 5672.9 5680.6 5688.7 5721.3 5728.2 1452.0 1461.5 1464.3 1476.4 1480.2 926.6 945.7 948.3 958.7 971.9 4893.3 4924.5 4961.4 4963.8 5043.2 5148.3 5153.8 5167.4 5184.7 5213.6 4673.4 4993.6 4997.4 5024.6 5026.2 2698.8 2730.5 2762.1 2786.8 2798.8 5341.7 5359.3 5378.4 5453.6 5471.6 5328.1 5341.0 5559.6 5746.0 5799.7 6150.3 6172.2 6173.0 6212.6 6214.6 5837.1 5906.9 5918.2 5920.5 5934.2 5643.6 5747.4 5782.9 5813.6 5831.7 6110.1 6160.1 6163.0 6164.3 6184.9 4131.2 4172.3 4204.3 4214.6 4228.3 5841.3 5845.1 5845.9 5870.9 5880.5 5767.7 5772.6 5786.5 5799.4 5807.7 5387.9 5638.5 5662.1 5665.5 5704.6 5602.3 5629.0 5653.6 5664.6 5670.4 5594.1 5595.6 5730.7 5760.7 5826.9 5585.4 5618.5 5626.9 5627.9 5632.4 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - PROCESS | 5528 | [#5] yui.html Cycles:180 Average:20401.36 Median:4969.91 stddev:27692.98 (557.2%) stddev-sans-first:27731.64 03:35:37 INFO - PROCESS | 5528 | Values: 820.2 842.8 851.4 874.3 926.4 14836.6 16826.9 17458.1 17519.2 17524.3 11770.0 15557.5 15709.5 15727.1 15743.1 865.4 889.7 893.7 896.2 896.6 862.7 864.7 870.5 871.2 871.9 768.2 915.3 919.5 919.5 919.7 776.6 779.2 897.7 924.6 925.9 664.0 664.7 664.9 665.1 666.8 16143.0 16146.7 16193.4 16194.2 16210.1 14459.9 14678.6 14794.5 14794.9 14831.4 13214.3 13552.9 13561.4 13577.2 13869.1 4743.4 4758.5 4823.5 4826.5 4882.9 70018.6 72017.6 77589.0 77789.0 78737.6 12257.4 12367.5 12621.2 12779.5 12967.0 22896.2 24740.3 24811.1 24883.3 24924.6 9568.9 9570.9 9639.7 9666.0 9686.8 4939.9 4999.9 5003.0 5051.4 5157.6 4012.2 4119.9 4123.9 4126.4 4127.5 664.2 672.3 672.9 680.1 696.8 604.2 667.9 675.4 680.6 714.2 9408.2 9465.8 9558.2 9618.1 9904.5 700.2 726.8 745.7 747.0 759.5 4486.1 4513.6 4558.4 4584.5 4586.8 70582.3 105463.5 110151.0 112735.4 113745.0 61336.4 61817.0 82404.6 95765.0 95918.0 85059.6 87035.6 87158.6 87533.6 87721.0 8967.5 9297.8 9305.3 9497.4 9628.1 48942.5 52429.2 53134.7 53283.5 54234.2 70309.0 74330.3 75132.2 75568.6 75728.6 12644.6 12808.6 12919.4 13210.3 13327.3 13937.6 14170.8 14172.4 15084.2 15131.8 12220.6 12383.7 14729.6 15380.0 15672.5 14128.9 14207.7 14380.3 14512.3 15057.9 14598.4 14919.4 15355.8 15375.2 15398.2 11988.6 12026.2 12228.6 12492.4 12781.2 12657.2 12732.8 12776.9 13093.5 13139.9 03:35:37 INFO - PROCESS | 5528 | -------- Summary: end -------- 03:35:37 INFO - PROCESS | 5528 | 03:35:37 INFO - TEST-INFO | 5528: exit 0 03:35:37 INFO - TEST-OK | dromaeo_css | took 1113718ms 03:35:37 INFO - TEST-START | kraken 03:35:37 INFO - operating with platform_type : linux_ 03:35:37 INFO - Initialising browser for kraken test... 03:35:37 INFO - TEST-INFO | started process 5714 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoBlOWS/profile http://localhost:37288/getInfo.html) 03:35:42 INFO - PROCESS | 5714 | __metrics Screen width/height:1600/1200 03:35:42 INFO - PROCESS | 5714 | colorDepth:24 03:35:42 INFO - PROCESS | 5714 | Browser inner width/height: 1024/697 03:35:42 INFO - PROCESS | 5714 | __metrics 03:35:43 ERROR - PROCESS | 5714 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:35:44 INFO - TEST-INFO | 5714: exit 0 03:35:44 INFO - Browser initialized. 03:35:44 INFO - Running cycle 1/1 for kraken test... 03:35:44 INFO - TEST-INFO | started process 5805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoBlOWS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:55 INFO - PROCESS | 5805 | RSS: Main: 145448960 03:35:55 INFO - PROCESS | 5805 | 03:35:58 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37288/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:59 INFO - PROCESS | 5805 | RSS: Main: 194801664 03:35:59 INFO - PROCESS | 5805 | 03:36:01 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37288/tests/kraken/driver.html?testName=audio-dft) 03:36:02 INFO - PROCESS | 5805 | RSS: Main: 191987712 03:36:02 INFO - PROCESS | 5805 | 03:36:05 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37288/tests/kraken/driver.html?testName=audio-fft) 03:36:05 INFO - PROCESS | 5805 | RSS: Main: 276688896 03:36:05 INFO - PROCESS | 5805 | 03:36:07 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37288/tests/kraken/driver.html?testName=audio-oscillator) 03:36:08 INFO - PROCESS | 5805 | RSS: Main: 185548800 03:36:08 INFO - PROCESS | 5805 | 03:36:10 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37288/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:11 INFO - PROCESS | 5805 | RSS: Main: 185057280 03:36:11 INFO - PROCESS | 5805 | 03:36:14 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37288/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:14 INFO - PROCESS | 5805 | RSS: Main: 220352512 03:36:14 INFO - PROCESS | 5805 | 03:36:18 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37288/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:18 INFO - PROCESS | 5805 | RSS: Main: 220483584 03:36:18 INFO - PROCESS | 5805 | 03:36:21 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37288/tests/kraken/driver.html?testName=json-parse-financial) 03:36:22 INFO - PROCESS | 5805 | RSS: Main: 220164096 03:36:22 INFO - PROCESS | 5805 | 03:36:23 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37288/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:24 INFO - PROCESS | 5805 | RSS: Main: 200237056 03:36:24 INFO - PROCESS | 5805 | 03:36:25 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:26 INFO - PROCESS | 5805 | RSS: Main: 201482240 03:36:26 INFO - PROCESS | 5805 | 03:36:28 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:29 INFO - PROCESS | 5805 | RSS: Main: 211238912 03:36:29 INFO - PROCESS | 5805 | 03:36:31 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:32 INFO - PROCESS | 5805 | RSS: Main: 214974464 03:36:32 INFO - PROCESS | 5805 | 03:36:35 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:35 INFO - PROCESS | 5805 | RSS: Main: 213684224 03:36:35 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | Cycle 1(1): loaded http://localhost:37288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37288/tests/kraken/driver.html?testName=ai-astar) 03:36:37 INFO - PROCESS | 5805 | RSS: Main: 255836160 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | __start_tp_report 03:36:37 INFO - PROCESS | 5805 | _x_x_mozilla_page_load 03:36:37 INFO - PROCESS | 5805 | _x_x_mozilla_page_load_details 03:36:37 INFO - PROCESS | 5805 | |i|pagename|runs| 03:36:37 INFO - PROCESS | 5805 | |0;ai-astar;110;104;110;108;109;109;109;109;109;107 03:36:37 INFO - PROCESS | 5805 | |1;audio-beat-detection;145;124;123;177;124;123;150;123;160;121 03:36:37 INFO - PROCESS | 5805 | |2;audio-dft;161;160;160;161;160;163;161;159;159;160 03:36:37 INFO - PROCESS | 5805 | |3;audio-fft;76;74;76;122;75;75;75;73;101;74 03:36:37 INFO - PROCESS | 5805 | |4;audio-oscillator;84;85;113;84;85;85;116;83;84;122 03:36:37 INFO - PROCESS | 5805 | |5;imaging-gaussian-blur;116;117;116;117;116;116;117;116;117;116 03:36:37 INFO - PROCESS | 5805 | |6;imaging-darkroom;146;146;145;145;147;145;145;145;145;145 03:36:37 INFO - PROCESS | 5805 | |7;imaging-desaturate;94;97;95;95;94;94;95;94;94;94 03:36:37 INFO - PROCESS | 5805 | |8;json-parse-financial;50;55;53;50;51;51;51;51;53;50 03:36:37 INFO - PROCESS | 5805 | |9;json-stringify-tinderbox;59;59;59;59;60;60;58;59;60;60 03:36:37 INFO - PROCESS | 5805 | |10;stanford-crypto-aes;76;75;81;75;77;77;77;77;122;77 03:36:37 INFO - PROCESS | 5805 | |11;stanford-crypto-ccm;123;116;125;125;139;122;122;126;133;121 03:36:37 INFO - PROCESS | 5805 | |12;stanford-crypto-pbkdf2;153;151;152;153;155;157;157;155;155;155 03:36:37 INFO - PROCESS | 5805 | |13;stanford-crypto-sha256-iterative;61;61;62;61;62;68;61;63;60;61 03:36:37 INFO - PROCESS | 5805 | __end_tp_report 03:36:37 INFO - PROCESS | 5805 | __start_cc_report 03:36:37 INFO - PROCESS | 5805 | _x_x_mozilla_cycle_collect,1574 03:36:37 INFO - PROCESS | 5805 | __end_cc_report 03:36:37 INFO - PROCESS | 5805 | __startTimestamp1467542197526__endTimestamp 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | ------- Summary: start ------- 03:36:37 INFO - PROCESS | 5805 | Number of tests: 14 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.00 stddev:1.78 (1.6%) stddev-sans-first:1.79 03:36:37 INFO - PROCESS | 5805 | Values: 110.0 104.0 110.0 108.0 109.0 109.0 109.0 109.0 109.0 107.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:134.50 stddev:19.84 (14.8%) stddev-sans-first:20.84 03:36:37 INFO - PROCESS | 5805 | Values: 145.0 124.0 123.0 177.0 124.0 123.0 150.0 123.0 160.0 121.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 03:36:37 INFO - PROCESS | 5805 | Values: 161.0 160.0 160.0 161.0 160.0 163.0 161.0 159.0 159.0 160.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:16.29 (21.7%) stddev-sans-first:17.13 03:36:37 INFO - PROCESS | 5805 | Values: 76.0 74.0 76.0 122.0 75.0 75.0 75.0 73.0 101.0 74.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:84.00 stddev:15.96 (19.0%) stddev-sans-first:16.51 03:36:37 INFO - PROCESS | 5805 | Values: 84.0 85.0 113.0 84.0 85.0 85.0 116.0 83.0 84.0 122.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:36:37 INFO - PROCESS | 5805 | Values: 116.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 117.0 116.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 03:36:37 INFO - PROCESS | 5805 | Values: 146.0 146.0 145.0 145.0 147.0 145.0 145.0 145.0 145.0 145.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 03:36:37 INFO - PROCESS | 5805 | Values: 94.0 97.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 94.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.00 stddev:1.65 (3.2%) stddev-sans-first:1.66 03:36:37 INFO - PROCESS | 5805 | Values: 50.0 55.0 53.0 50.0 51.0 51.0 51.0 51.0 53.0 50.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.50 stddev:0.67 (1.1%) stddev-sans-first:0.71 03:36:37 INFO - PROCESS | 5805 | Values: 59.0 59.0 59.0 59.0 60.0 60.0 58.0 59.0 60.0 60.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:77.00 stddev:14.36 (18.7%) stddev-sans-first:15.10 03:36:37 INFO - PROCESS | 5805 | Values: 76.0 75.0 81.0 75.0 77.0 77.0 77.0 77.0 122.0 77.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:125.00 stddev:6.49 (5.2%) stddev-sans-first:6.84 03:36:37 INFO - PROCESS | 5805 | Values: 123.0 116.0 125.0 125.0 139.0 122.0 122.0 126.0 133.0 121.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.30 Median:155.00 stddev:2.00 (1.3%) stddev-sans-first:2.07 03:36:37 INFO - PROCESS | 5805 | Values: 153.0 151.0 152.0 153.0 155.0 157.0 157.0 155.0 155.0 155.0 03:36:37 INFO - PROCESS | 5805 | 03:36:37 INFO - PROCESS | 5805 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:61.50 stddev:2.26 (3.7%) stddev-sans-first:2.37 03:36:37 INFO - PROCESS | 5805 | Values: 61.0 61.0 62.0 61.0 62.0 68.0 61.0 63.0 60.0 61.0 03:36:37 INFO - PROCESS | 5805 | -------- Summary: end -------- 03:36:37 INFO - PROCESS | 5805 | 03:36:38 INFO - TEST-INFO | 5805: exit 0 03:36:38 INFO - TEST-OK | kraken | took 60572ms 03:36:38 INFO - SUITE-END | took 1174s 03:36:38 INFO - Completed test suite (00:19:34) 03:36:38 INFO - javascript benchmark 03:36:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.8327583620817, 3389.1357703785548, 3396.269801992571, 3415.992282276728, 3438.9656103438974, 5564.276644036275, 5633.070543360346, 5657.745401456934, 5681.517071048966, 5682.6306290091115, 5565.9443405565935, 5615.7472913718875, 5626.409227031164, 5629.521490673298, 5637.238972738679, 4816.710997340153, 4967.776450059738, 5081.212412076134, 5082.339295891529, 5115.335006449156, 4983.277424773406, 5019.870529130934, 5031.672941258825, 5035.723035233061, 5036.0, 5011.473795251482, 5048.318477005612, 5074.84775456735, 5135.794568217267, 5162.22566615007, 5195.506426889439, 5217.243499692523, 5218.556422704075, 5218.947810521884, 5245.554127899114, 4910.042541704369, 5010.674306170124, 5047.87380315495, 5124.410692770304, 5136.563392111675, 5331.040412725708, 5400.378956420022, 5402.297701298845, 5415.214793854869, 5445.401005889349, 5970.313413957407, 6014.3985601439945, 6019.217501724792, 6027.758889644409, 6038.3659715729655, 5969.522438204933, 5992.310884248323, 6000.399960004008, 6072.574919755619, 6096.7561297548045, 5624.71876406178, 5649.548036157097, 5664.745086471119, 5675.716214189274, 5679.488846003838, 5894.027485464936, 6159.076138579159, 6166.907496387557, 6171.352008039091, 6171.814845554641, 5788.53691704671, 5813.331466881278, 5826.504747096459, 5935.080062590306, 5935.317438494542, 5972.462478376967, 5984.401559844068, 5991.071383935497, 6020.187274717945, 6021.7591296347655, 5723.885522289531, 5957.344692083867, 5965.463108320185, 5965.492933100648, 5966.075258334965, 6008.969955150196, 6087.269527656621, 6091.329953705089, 6094.878102437927, 6138.14066030756, 4674.392328997209, 4679.508651591602, 4684.765761711901, 4686.039361930796, 4712.882177945578, 4038.0712436139265, 4098.159877225162, 4151.958480415157, 4180.226658068267, 4203.894902627459, 5857.004309267435, 5857.88284234313, 5860.589758716849, 5864.38423965486, 5890.793822216202, 6117.974595441065, 6134.478569321657, 6136.7852125175405, 6138.2121944883065, 6145.477634401126, 5616.185653080281, 5682.204491371212, 5722.0, 5730.828075157752, 5846.6784326862435, 5147.356580427447, 5158.535731784157, 5162.199859021783, 5163.638545301793, 5188.169892817131, 6331.810045698637, 6341.143945567289, 6342.0, 6342.175517182829, 6342.334054924258, 5843.678597677169, 6000.339962604023, 6022.5784195105925, 6040.516758659384, 6052.969735151296, 6282.8429289266305, 6288.622682638873, 6337.144485494584, 6338.461230795249, 6339.049142628458, 5899.115132730125, 5920.822375328747, 5928.199693041384, 5933.169356290036, 5973.283206015132, 5792.797252096157, 5887.470127688529, 5922.230110085834, 5933.258342707161, 6019.638821670714, 5860.531157507475, 5860.853478663067, 5863.794767183128, 5864.442877926761, 5868.735906884286, 5772.393898640582, 5809.622374545641, 5810.215620891295, 5817.970910145422, 5819.82540523785, 5584.246126772834, 5666.405027472221, 5669.064604340402, 5706.258186435737, 5754.53963682896, 5875.794347197828, 5887.20522729426, 5921.082232254007, 5927.8221653350465, 5929.377415371497, 5547.5007249347755, 5758.222639943721, 5767.942320576741, 5770.6249093808765, 5785.0], "value": 5544.194257865057, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.44920948123, 638.1671918146818, 645.4900628503481, 652.9085927970082, 667.5560752099854, 33730.83134584327, 46306.53693463065, 47434.28848567274, 47437.76281118598, 47523.52476475239, 49293.26060109101, 49498.25752613714, 49678.0, 49792.50207497924, 49887.0, 8514.48913065218, 8753.3872628916, 9137.131972462623, 9299.16307532322, 10917.563297468092, 10700.411477368742, 11129.0, 11134.27627204229, 11138.053265472445, 11139.665810025712, 11204.047655949254, 11242.213045086806, 11245.437728113562, 11255.0, 11255.099592032659, 8481.66073357065, 10936.179786516042, 11105.444727763619, 11154.274972126866, 11222.831657525143, 10621.0, 11023.559057637685, 11040.33757974524, 11040.33757974524, 11051.336919784839, 45660.315095273596, 46092.53907460949, 46187.0, 46197.0, 46225.53774462246, 3885.067583779901, 3893.824777885002, 3923.234969181011, 3941.625545573166, 4331.090471001094, 35014.0, 35389.823050884836, 35433.82283088594, 35682.64317356845, 37323.44014839779, 4644.674872758908, 4672.322513235596, 4703.764811759433, 4712.31671407644, 4801.855944321641, 386446.0, 389166.0, 389372.0, 391213.0, 391461.0, 38524.614753852664, 39736.20527589432, 71218.64390678117, 72292.63853680804, 72295.63852180705, 40932.59067409288, 41508.58491415108, 41596.0, 41637.79181104136, 41664.0, 16644.916775416288, 16715.247813848408, 16759.0, 16768.664626707643, 16772.329106835834, 13890.09714368583, 14860.62848428798, 14885.851141488447, 14953.850461495464, 15008.174550399834, 9443.386179898285, 9513.571889265, 9531.857022144673, 9581.952090239503, 9595.568199431042, 3582.1044738815294, 3649.069487280726, 3670.541182352206, 3671.430928206132, 3741.0460332615007, 1097.8682558092921, 1138.960101550635, 1141.7431078007382, 1143.1255089856268, 1167.6146871532374, 34785.13037174091, 35285.29429411398, 35379.292414152085, 35469.82265088658, 35503.82248088743, 1183.5206741269799, 1183.9408029598485, 1204.9819252711216, 1230.3233221728015, 1263.8988880889506, 17635.29458821633, 18277.451676449728, 18469.630607388044, 18532.81467185338, 18558.257669693332, 593892.0, 594999.0, 596114.0, 596133.0, 602121.0, 65121.67439162869, 66146.66926665336, 66148.0, 66170.0, 66300.33699663039, 1393.1989106263736, 1436.7126574685315, 1454.8472410396967, 1498.8276348219877, 1504.8344682084962, 34405.0, 35784.821075894455, 36073.278534429686, 36114.0, 36396.27207455889, 56602.0, 57646.13530796958, 57870.131948019975, 57944.420555793906, 58027.0, 447691.0, 450313.0, 450647.0, 451303.0, 451514.0, 28047.859760701067, 29922.700772992863, 30955.226119347197, 31138.688613114486, 32808.0, 52177.47822521726, 53245.4675453256, 53956.73021634867, 54004.0, 54066.459335407715, 44215.33676994912, 44762.776186120165, 44784.552154478035, 45612.543874560826, 46429.0, 44224.11551769011, 44261.557384425745, 44289.557104428546, 44448.0, 45015.324770127816, 44344.0, 44400.333994990746, 44600.33099503575, 44708.55291447174, 46574.767126165505, 39126.6087339123, 40564.1887162261, 41160.38259426051, 41212.38181427341, 41271.380929285486, 30447.238819029702, 30586.235344116576, 30674.00480260155, 30865.0, 31079.378412432077], "value": 21033.52131319612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.12404137373585, 429.5940336382116, 435.35567360306743, 438.37750394439894, 442.65694087082505, 18812.247510099598, 19648.50878728031, 20567.28014519492, 20693.586128277424, 20707.792922070774, 17693.0, 19265.807341926575, 19450.416487505398, 20208.696869546933, 20229.39311820644, 5353.437889021655, 5544.667319960795, 5568.832935011957, 5578.86052848678, 5588.21764952907, 5430.56555475561, 5430.647007944471, 5463.0, 5498.532624726883, 5555.2778138841895, 5435.565154787627, 5505.256790333294, 5535.750891209885, 5546.833594992157, 5567.916481252784, 5453.918191227135, 5456.972715136419, 5497.45025497451, 5860.97069514654, 6169.444749972479, 6034.396560343975, 6052.394760523956, 6054.273487181523, 6077.817665469999, 6078.696065196723, 14954.0, 15584.61038474036, 21919.0, 24381.14665986682, 24461.633075503883, 2230.542738738555, 2332.183735692511, 2514.748525147489, 2556.8338058026357, 2582.19951814938, 10736.409497477636, 10801.83797243042, 10904.0, 11235.325880447199, 11523.654290371302, 2037.847161462896, 2046.72369230155, 2149.7850214978534, 2163.8052575268143, 2293.908243670251, 82714.58642706748, 85672.0, 86018.13981860164, 88710.556447218, 89328.55335723345, 11408.201425900108, 11665.300081995107, 11737.178397512092, 11784.646460606196, 12065.215760975509, 16693.91653041727, 16749.330026799034, 16976.066316352473, 17061.7440738389, 17074.658506829794, 8916.331275154389, 8974.41666291689, 9067.954660226656, 9152.725418237464, 9206.677766278148, 8906.643734250558, 8926.553672316488, 8948.910510894808, 9001.639934402681, 9036.728898133066, 5163.0, 5168.405633352213, 5171.095058364846, 5187.377514698261, 5253.054450198924, 2012.4063401296653, 2013.1645367172753, 2014.4963759060236, 2045.9795402046086, 2100.411884672294, 954.398728800851, 1025.4257615735212, 1055.7360659835042, 1106.8505751723415, 1124.12318391653, 19428.51428714265, 19964.401067967985, 20279.4930126748, 20365.08357123933, 20460.46546509018, 1028.881678606955, 1048.3028785857318, 1066.6746642274181, 1076.445630500293, 1097.9890201097887, 10554.47227638615, 11040.613578524713, 11155.498002589902, 11166.385848778233, 11200.887991120147, 390383.0, 393788.0931786026, 397381.0, 491643.0, 513120.0, 103709.0, 107853.0, 109438.0, 111144.0, 111539.0, 1050.4642632257603, 1090.825467925144, 1119.412308538024, 1128.2553514680271, 1129.0290350298571, 17654.558636034202, 17736.02451865108, 17882.0, 18070.0, 18113.36603218881, 42013.78993105015, 43485.3477197836, 43662.12675746531, 43929.0, 44015.55984440115, 62597.68701156465, 63856.68071659612, 64449.67775161094, 64669.0, 64961.0, 8978.955105224431, 9285.675001374919, 9300.6279748812, 9374.343795934492, 9516.00081991381, 11199.552017919192, 13295.933520332723, 13493.527726529524, 13681.0, 13685.657858553615, 11810.822837657613, 11891.821622675494, 11915.940420297842, 11965.76068478608, 12315.815262770886, 11482.36846973391, 11557.768844623228, 11611.187216894736, 11781.352025638673, 11876.346800925687, 11860.94069529647, 12013.219140755822, 12250.75498490043, 12435.813462798245, 12646.114771965875, 9093.863592045993, 9151.947526034324, 9275.39709918853, 9326.766830829283, 9452.905470945478, 9116.954415227881, 12003.339816310187, 12252.632421027383, 12359.35006096906, 12555.93722031384], "value": 9616.07347412922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.3004162939333, 1584.5642448326712, 1601.4635097242424, 1833.825786550278, 1966.2331690640654, 9033.367664263496, 9316.813663726722, 9417.670381536647, 9704.466254356008, 9707.271954603415, 9442.575084121214, 9650.08324209199, 9650.469224192666, 9654.42073475592, 9661.710148695533, 2228.342638921522, 2481.081999660128, 2529.215943057649, 2558.5010922870047, 2560.0527804712283, 2562.1929092335854, 2579.496998085374, 2582.5093232285803, 2584.987075064622, 2588.948221035578, 2511.3721569607596, 2553.6041913503436, 2554.2337298810376, 2556.846589204654, 2557.4757174779124, 2107.230860735836, 2357.2574638988663, 2399.0763556030965, 2455.8526488410575, 2458.483718419134, 2451.4851881104987, 2454.901803927841, 2473.641322008317, 2476.6780318558654, 2502.94994100117, 9732.026797320283, 9788.608455661764, 9793.0, 9801.11789938909, 9804.705858824247, 5774.769009239625, 5919.112133180014, 5922.14128951304, 5923.5853490255695, 5923.970380148115, 3490.5462289902403, 3606.278744251134, 3612.8916132516065, 3647.0335361129323, 3654.3422184006868, 1189.1913498820797, 1209.9758004839941, 1212.2060050666828, 1216.4586758892297, 1264.3109505319624, 13863.23752193629, 13932.233727145003, 13937.442502299897, 14017.64955876101, 14056.71886562263, 5103.515166059218, 5176.171812509981, 5237.423883372826, 5264.631475796734, 5316.122839731401, 11609.419529023515, 11684.18210725258, 11687.123465740104, 11693.889080537336, 11703.414829258674, 5941.22764040672, 5944.524438044946, 5947.672877991752, 5954.583179177416, 5958.374370691102, 4701.177293973541, 5279.894402112013, 5411.080116380225, 5431.185322201702, 5522.061249587579, 4367.5414081520985, 4403.14138742942, 4456.598906098467, 4470.485894122153, 4495.797059440916, 1381.86181381861, 1397.7484052870377, 1404.1996062244423, 1405.051590176647, 1405.9367328470244, 933.8225737109927, 944.730751735752, 951.9095685909828, 1079.7894410589859, 1083.3608171179042, 6482.546221764431, 6611.63636000015, 6628.204615446178, 6676.766313179112, 6792.0, 999.7850462150673, 1011.8178727828914, 1024.774549599087, 1041.9687409377732, 1061.9787604247872, 4382.9342559861625, 4420.204363214652, 4460.375547423363, 4466.553344665507, 4559.293309537027, 11871.643850684493, 12203.633890983285, 12219.083568732382, 12227.877721222852, 12232.816507752214, 11596.0, 11988.460519276654, 12094.0, 12115.39423028845, 12200.206986546023, 9003.0, 9133.0, 9152.267818574632, 9204.493752843659, 9206.63173473067, 6676.265610782907, 6702.932970670231, 6766.221884483348, 6809.285025072296, 6875.4155896749335, 10645.201609879136, 11038.006579407891, 11124.221304508606, 11298.152638552307, 11303.265287756269, 11340.943295283801, 11408.372539510407, 11430.828537571777, 11438.656840294805, 11512.0, 7485.0643669541305, 8431.241188293083, 8607.397482176188, 8658.437201581877, 8825.426347287406, 9532.0, 9627.085426884434, 9631.71104866855, 9660.565274562801, 9668.951655241679, 8033.55815430157, 8200.097989221062, 8242.670293188205, 8361.916380836114, 8366.414350995372, 8191.754247372588, 8261.297789687704, 8349.332053435832, 8396.244338009608, 8493.065762765968, 7165.176004759311, 7518.473706840468, 7791.571463569559, 7798.415118865994, 7966.760997170094, 5606.523445507178, 5660.490555849993, 5685.857853553694, 5861.677607731615, 5865.296164460292, 5688.658680479184, 5710.914336285042, 5741.081426971665, 5875.941240587539, 6071.241094863142], "value": 5100.416311111492, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.69765699155715, 360.15363894847104, 363.9053846000039, 367.4414889368162, 367.62318623411, 5382.2195781611645, 5454.863628409292, 5465.754041068157, 5478.150886612571, 5507.889842203153, 5341.492558206974, 5402.486763757437, 5436.972815135929, 5445.767235433473, 5459.672419654824, 4107.822853887437, 4231.4499115114995, 4233.1322078973735, 4238.9152216955645, 4248.723832950848, 4152.584741525839, 4219.282721937263, 4224.72539284947, 4225.8520951766695, 4244.660427165819, 4220.36694495825, 4231.322988321869, 4235.385869048986, 4240.215560121372, 4262.126264115865, 4156.771377574232, 4214.651311580295, 4215.87352379426, 4221.670173895213, 4268.637165840907, 3714.1086139326635, 3721.274351501458, 3723.5345581802276, 3726.869559565402, 3743.644353786386, 5179.197224430181, 5184.974075129638, 5203.791848326063, 5206.895862082775, 5217.765200565984, 4670.673052886299, 4760.5715485606115, 4783.354247176621, 4829.855104346875, 4833.685810422311, 5603.691796951166, 5639.436056394369, 5648.096304591287, 5648.350439699446, 5657.23627310312, 4110.116324990141, 4131.132462182945, 4134.855280065183, 4139.172165566899, 4149.688773342012, 5661.980843448177, 5890.234269544974, 5890.469857712784, 5910.615809972338, 5921.703914804286, 4110.355857656961, 4215.0, 4233.30150525166, 4240.936385954213, 4245.235857545671, 4283.271843786564, 4287.0, 4287.292596721569, 4291.334843099325, 4296.119295544406, 4595.333676616872, 4830.951690483121, 4846.176150054499, 4849.927251091236, 4880.975595122001, 5672.858178545569, 5680.630759000652, 5688.744006519716, 5721.256236689203, 5728.198052272685, 1451.985480145185, 1461.5103940179947, 1464.3306630441305, 1476.372541669786, 1480.2154857925316, 926.6339795780628, 945.7021038372891, 948.2793077261193, 958.7459323279337, 971.9271054670928, 4893.266010098514, 4924.482929292444, 4961.362750292395, 4963.776630051656, 5043.218301163325, 5148.253503242084, 5153.819616313411, 5167.379914410295, 5184.662996905189, 5213.6350455467755, 4673.3690951721765, 4993.626752643052, 4997.401870653124, 5024.618229986783, 5026.246063090565, 2698.838069715784, 2730.5494593392264, 2762.06089929425, 2786.7770578353684, 2798.8040837141207, 5341.691285635045, 5359.330083739533, 5378.381486129067, 5453.590980676386, 5471.644343117685, 5328.135152696525, 5340.9704312361055, 5559.583031272671, 5745.971270143623, 5799.739011744482, 6150.323464418911, 6172.166757487771, 6173.0, 6212.596181248114, 6214.564980451415, 5837.09525023622, 5906.881862362815, 5918.201042859159, 5920.467157955804, 5934.169216309634, 5643.604947653625, 5747.425257474386, 5782.942170578241, 5813.575673959906, 5831.679257640871, 6110.083487476736, 6160.10678451631, 6162.969185154046, 6164.291106522807, 6184.907226391518, 4131.235721391552, 4172.31156859127, 4204.348326009474, 4214.557471465575, 4228.323468245066, 5841.32824725162, 5845.1232315153065, 5845.912311315419, 5870.853228669317, 5880.470757631833, 5767.7404516796005, 5772.5959182856795, 5786.508146807569, 5799.362070172193, 5807.738651760766, 5387.946120538744, 5638.4925356718095, 5662.122371032497, 5665.518430933417, 5704.575555389026, 5602.2717046783655, 5629.0, 5653.632513886749, 5664.603477756517, 5670.404607516316, 5594.1049432092295, 5595.636283641713, 5730.656160630376, 5760.683162426107, 5826.912596310974, 5585.441455854545, 5618.522425593706, 5626.887462250814, 5627.8593035174445, 5632.436756324499], "value": 4288.607595036496, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.2289847543309, 842.7640260726997, 851.4082712514804, 874.2874557235854, 926.4024704065882, 14836.55490335289, 16826.91586542067, 17458.12709364533, 17519.211635476404, 17524.299028038862, 11770.0, 15557.53327400177, 15709.450169244079, 15727.056376617411, 15743.134127622976, 865.3639574912451, 889.6975028490313, 893.6648756716231, 896.2337201692557, 896.6413434626137, 862.7282406042077, 864.749222725412, 870.5342641686699, 871.1636828644508, 871.8866547348836, 768.1511929318079, 915.2769312243353, 919.4850883505225, 919.5034681272105, 919.6965001549472, 776.6427443376053, 779.2441331908041, 897.6723495924006, 924.5931790012373, 925.9398139120935, 664.0205696597528, 664.6610228783306, 664.8504086580529, 665.0556210181554, 666.7699643622991, 16143.0, 16146.677066458606, 16193.433229837017, 16194.190290485547, 16210.108444035573, 14459.927700361432, 14678.559643210721, 14794.482193123189, 14794.852051479455, 14831.406743730346, 13214.273214973173, 13552.932235338858, 13561.389737461739, 13577.1853688779, 13869.09850859691, 4743.407074115736, 4758.547937945889, 4823.493533179036, 4826.517348265181, 4882.902341953141, 70018.64990675065, 72017.63991180062, 77589.0, 77789.0, 78737.60631196864, 12257.38713064361, 12367.505299787908, 12621.242725436503, 12779.488820447265, 12967.0, 22896.198633047763, 24740.25779226626, 24811.13161039391, 24883.253502394957, 24924.626130608056, 9568.856467152998, 9570.904290957002, 9639.71080867575, 9666.0, 9686.75783105428, 4939.925901111486, 4999.900499492527, 5003.0, 5051.444341122474, 5157.613179011589, 4012.217617564547, 4119.855805046809, 4123.8556650517085, 4126.42230087788, 4127.525334586561, 664.1598378051768, 672.3008071605573, 672.8687905858409, 680.119245576979, 696.7561353526327, 604.1873679900526, 667.9064930909676, 675.4360109308782, 680.6392611915693, 714.1751277274756, 9408.200302974325, 9465.810683786423, 9558.235341172689, 9618.086281803218, 9904.504774761233, 700.1702981966284, 726.8364617960916, 745.7315366468067, 747.0, 759.506320891425, 4486.12520558488, 4513.616342610849, 4558.361829343895, 4584.541545845389, 4586.816527338936, 70582.29417705757, 105463.47268263763, 110151.0, 112735.43632281786, 113745.0, 61336.38663613396, 61817.0, 82404.58797705972, 95765.0, 95918.0, 85059.57470212609, 87035.56482217548, 87158.56420717856, 87533.5623321892, 87721.0, 8967.461952282623, 9297.814043718952, 9305.348625596143, 9497.43015419077, 9628.085331893459, 48942.51057489379, 52429.21356179737, 53134.73432632812, 53283.46716532785, 54234.186487201936, 70309.0, 74330.25669743233, 75132.24867751253, 75568.62215688886, 75728.62135689287, 12644.557440489538, 12808.615741527768, 12919.418626162033, 13210.339483026002, 13327.267000315076, 13937.58187254384, 14170.787438188641, 14172.362243698852, 15084.245787710352, 15131.773023404878, 12220.64950858235, 12383.69040773988, 14729.55811325662, 15380.0, 15672.529824105295, 14128.858711413166, 14207.715845683235, 14380.28098595045, 14512.346944387738, 15057.924710376377, 14598.416063357347, 14919.403223871357, 15355.769663454834, 15375.154366509949, 15398.230088495306, 11988.64034078979, 12026.218295810744, 12228.633141005785, 12492.437840297027, 12781.16922400041, 12657.177283476545, 12732.809007864704, 12776.936115319364, 13093.541726039542, 13139.934300328438], "value": 7903.724653443099, "unit": "score"}], "name": "dromaeo_css", "value": 7607.771964528675, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 110.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 107.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 124.0, 123.0, 177.0, 124.0, 123.0, 150.0, 123.0, 160.0, 121.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 160.0, 163.0, 161.0, 159.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 122.0, 75.0, 75.0, 75.0, 73.0, 101.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 113.0, 84.0, 85.0, 85.0, 116.0, 83.0, 84.0, 122.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 147.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 55.0, 53.0, 50.0, 51.0, 51.0, 51.0, 51.0, 53.0, 50.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 58.0, 59.0, 60.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 81.0, 75.0, 77.0, 77.0, 77.0, 77.0, 122.0, 77.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 116.0, 125.0, 125.0, 139.0, 122.0, 122.0, 126.0, 133.0, 121.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 152.0, 153.0, 155.0, 157.0, 157.0, 155.0, 155.0, 155.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 62.0, 68.0, 61.0, 63.0, 60.0, 61.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1472.1000000000001, "alertThreshold": 2.0}]} 03:36:38 INFO - Return code: 0 03:36:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:36:38 ERROR - # TBPL SUCCESS # 03:36:38 INFO - Running post-action listener: _resource_record_post_action 03:36:38 INFO - ##### 03:36:38 INFO - ##### Finished run-tests step (success) 03:36:38 INFO - ##### 03:36:38 INFO - Running post-run listener: _resource_record_post_run 03:36:38 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504758272; Read time: 16; Write time: 498748 03:36:38 INFO - TinderboxPrint: CPU usage
13.1% 03:36:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 03:36:38 INFO - TinderboxPrint: I/O write bytes / time
504,758,272 / 498,748 03:36:38 INFO - TinderboxPrint: CPU idle
8,261.0 (86.9%) 03:36:38 INFO - TinderboxPrint: CPU user
1,199.2 (12.6%) 03:36:38 INFO - TinderboxPrint: Swap in / out
0 / 0 03:36:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 223887360; Read time: 0; Write time: 257028 03:36:38 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251547648; Read time: 16; Write time: 236488 03:36:39 INFO - Running post-run listener: _upload_blobber_files 03:36:39 INFO - Blob upload gear active. 03:36:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:36:39 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 03:36:39 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:36:39 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:36:39 INFO - (blobuploader) - INFO - Open directory for files ... 03:36:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:36:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:43 INFO - (blobuploader) - INFO - Done attempting. 03:36:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:36:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:36:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:44 INFO - (blobuploader) - INFO - Done attempting. 03:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:36:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:45 INFO - (blobuploader) - INFO - Done attempting. 03:36:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:45 INFO - Return code: 0 03:36:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/365ed40a0f1c769cf5cb473801057413ede029c76b4a0ca3a6ee52c7b6c715d1604b9528ad7c56cf7f3b79434330aad5b6dc4d5e806c74a51c54f10d9268b419", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01d9ada489fb83300348a65433e56eb32e9b19a7c2fb9ac5583377740cbed6d3ccf220fa060ddfa3342d8056029e83d5ddc591c660564d404c786f73a04e242c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31e3d91029ebbc9c5833676202b13de28db50ca0c2fa0660d730d5a9fd0db39441d5d5f4c20dbbecff719114eb054d7e04d1c34d96321af1948d2d2c3d654041"} 03:36:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:45 INFO - Contents: 03:36:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/365ed40a0f1c769cf5cb473801057413ede029c76b4a0ca3a6ee52c7b6c715d1604b9528ad7c56cf7f3b79434330aad5b6dc4d5e806c74a51c54f10d9268b419", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01d9ada489fb83300348a65433e56eb32e9b19a7c2fb9ac5583377740cbed6d3ccf220fa060ddfa3342d8056029e83d5ddc591c660564d404c786f73a04e242c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31e3d91029ebbc9c5833676202b13de28db50ca0c2fa0660d730d5a9fd0db39441d5d5f4c20dbbecff719114eb054d7e04d1c34d96321af1948d2d2c3d654041"} 03:36:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:45 INFO - Copying logs to upload dir... 03:36:45 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.267271 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-07-03 03:36:43.359182) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 03:36:43.362933) ========= 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=fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/365ed40a0f1c769cf5cb473801057413ede029c76b4a0ca3a6ee52c7b6c715d1604b9528ad7c56cf7f3b79434330aad5b6dc4d5e806c74a51c54f10d9268b419", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01d9ada489fb83300348a65433e56eb32e9b19a7c2fb9ac5583377740cbed6d3ccf220fa060ddfa3342d8056029e83d5ddc591c660564d404c786f73a04e242c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31e3d91029ebbc9c5833676202b13de28db50ca0c2fa0660d730d5a9fd0db39441d5d5f4c20dbbecff719114eb054d7e04d1c34d96321af1948d2d2c3d654041"} build_url:https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/bISpOE5YRweBC8m1hn35LA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/365ed40a0f1c769cf5cb473801057413ede029c76b4a0ca3a6ee52c7b6c715d1604b9528ad7c56cf7f3b79434330aad5b6dc4d5e806c74a51c54f10d9268b419", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01d9ada489fb83300348a65433e56eb32e9b19a7c2fb9ac5583377740cbed6d3ccf220fa060ddfa3342d8056029e83d5ddc591c660564d404c786f73a04e242c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31e3d91029ebbc9c5833676202b13de28db50ca0c2fa0660d730d5a9fd0db39441d5d5f4c20dbbecff719114eb054d7e04d1c34d96321af1948d2d2c3d654041"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 03:36:43.387841) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:36:43.388154) ========= 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=fa44defc139c4ba8574fe040000001db-1467540220.856475-1878668596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004730 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 03:36:43.470620) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 03:36:43.470950) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 03:36:43.471243) ========= ========= Total master_lag: 0.16 =========