builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1469873842.12 results: success (0) buildid: 20160729170826 builduid: 5bb96b5f7e074e778445d151ad800bd4 revision: 589e1f984537e280ff84591e94afdc3c5bfe2710 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.121365) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.121821) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.122132) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.137048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.137402) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 03:17:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 27.0M=0s 2016-07-30 03:17:24 (27.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.072297 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.224460) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.224828) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017593 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.274024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:22.274380) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 589e1f984537e280ff84591e94afdc3c5bfe2710 --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 589e1f984537e280ff84591e94afdc3c5bfe2710 --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=97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 03:17:24,175 truncating revision to first 12 chars 2016-07-30 03:17:24,175 Setting DEBUG logging. 2016-07-30 03:17:24,175 attempt 1/10 2016-07-30 03:17:24,175 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/589e1f984537?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 03:17:24,611 unpacking tar archive at: mozilla-aurora-589e1f984537/testing/mozharness/ program finished with exit code 0 elapsedTime=0.782093 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:23.070108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:23.070460) ========= script_repo_revision: 589e1f984537e280ff84591e94afdc3c5bfe2710 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:23.070857) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:23.071137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:17:23.079656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-30 03:17:23.079984) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:17:25 INFO - MultiFileLogger online at 20160730 03:17:25 in /builds/slave/test 03:17:25 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:17:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:17:25 INFO - {'append_to_log': False, 03:17:25 INFO - 'base_work_dir': '/builds/slave/test', 03:17:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:17:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:17:25 INFO - 'branch': 'Mozilla-Aurora', 03:17:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:17:25 INFO - 'config_files': ('talos/linux_config.py',), 03:17:25 INFO - 'default_actions': ('clobber', 03:17:25 INFO - 'read-buildbot-config', 03:17:25 INFO - 'download-and-extract', 03:17:25 INFO - 'populate-webroot', 03:17:25 INFO - 'create-virtualenv', 03:17:25 INFO - 'install', 03:17:25 INFO - 'run-tests'), 03:17:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:17:25 INFO - 'download_minidump_stackwalk': True, 03:17:25 INFO - 'download_symbols': 'ondemand', 03:17:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:17:25 INFO - 'tooltool.py': '/tools/tooltool.py', 03:17:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:17:25 INFO - '/tools/misc-python/virtualenv.py')}, 03:17:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:17:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:17:25 INFO - 'installer_path': 'installer.exe', 03:17:25 INFO - 'log_level': 'info', 03:17:25 INFO - 'log_name': 'talos', 03:17:25 INFO - 'log_to_console': True, 03:17:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:17:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:17:25 INFO - 'opt_config_files': (), 03:17:25 INFO - 'pip_index': False, 03:17:25 INFO - 'sps_profile': False, 03:17:25 INFO - 'sps_profile_interval': 0, 03:17:25 INFO - 'suite': 'dromaeojs', 03:17:25 INFO - 'system_bits': '32', 03:17:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:17:25 INFO - 'title': 'talos-linux64-ix-068', 03:17:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:17:25 INFO - 'use_talos_json': True, 03:17:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:17:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:17:25 INFO - 'work_dir': 'build'} 03:17:25 INFO - ##### 03:17:25 INFO - ##### Running clobber step. 03:17:25 INFO - ##### 03:17:25 INFO - Running pre-action listener: _resource_record_pre_action 03:17:25 INFO - Running main action method: clobber 03:17:25 INFO - rmtree: /builds/slave/test/build 03:17:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:17:25 INFO - Running post-action listener: _resource_record_post_action 03:17:25 INFO - ##### 03:17:25 INFO - ##### Finished clobber step (success) 03:17:25 INFO - ##### 03:17:25 INFO - ##### 03:17:25 INFO - ##### Running read-buildbot-config step. 03:17:25 INFO - ##### 03:17:25 INFO - Running pre-action listener: _resource_record_pre_action 03:17:25 INFO - Running main action method: read_buildbot_config 03:17:25 INFO - Using buildbot properties: 03:17:25 INFO - { 03:17:25 INFO - "project": "", 03:17:25 INFO - "product": "firefox", 03:17:25 INFO - "script_repo_revision": "production", 03:17:25 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:17:25 INFO - "repository": "", 03:17:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:17:25 INFO - "buildid": "20160729170826", 03:17:25 INFO - "pgo_build": "True", 03:17:25 INFO - "basedir": "/builds/slave/test", 03:17:25 INFO - "buildnumber": 397, 03:17:25 INFO - "slavename": "talos-linux64-ix-068", 03:17:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:17:25 INFO - "platform": "ubuntu64_hw", 03:17:25 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:17:25 INFO - "branch": "mozilla-aurora", 03:17:25 INFO - "repo_path": "releases/mozilla-aurora", 03:17:25 INFO - "revision": "589e1f984537e280ff84591e94afdc3c5bfe2710", 03:17:25 INFO - "stage_platform": "linux64-pgo", 03:17:25 INFO - "builduid": "5bb96b5f7e074e778445d151ad800bd4", 03:17:25 INFO - "slavebuilddir": "test" 03:17:25 INFO - } 03:17:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:17:25 INFO - Running post-action listener: _resource_record_post_action 03:17:25 INFO - ##### 03:17:25 INFO - ##### Finished read-buildbot-config step (success) 03:17:25 INFO - ##### 03:17:25 INFO - ##### 03:17:25 INFO - ##### Running download-and-extract step. 03:17:25 INFO - ##### 03:17:25 INFO - Running pre-action listener: _resource_record_pre_action 03:17:25 INFO - Running main action method: download_and_extract 03:17:25 INFO - mkdir: /builds/slave/test/build/tests 03:17: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:17:25 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:17:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:17:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:17:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:26 INFO - Downloaded 1361 bytes. 03:17:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:17:26 INFO - Using the following test package requirements: 03:17:26 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:17:26 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:17:26 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:17:26 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'jsshell-linux-x86_64.zip'], 03:17:26 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:17:26 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:17:26 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:17:26 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:17:26 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:17:26 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:17:26 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:17:26 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:26 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:17:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:17:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:17:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:27 INFO - Downloaded 24496939 bytes. 03:17:27 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:17:27 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:17:28 INFO - Return code: 0 03:17:28 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:17:28 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:28 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:17:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:17:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:17:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:29 INFO - Downloaded 24496939 bytes. 03:17:29 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:17:29 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:17:31 INFO - Return code: 0 03:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:31 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:17:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:17:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:17:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:31 INFO - Downloaded 10833369 bytes. 03:17:31 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:17:31 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:17:32 INFO - Return code: 0 03:17: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:17:32 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:17:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:17:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:17:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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:17:33 INFO - Downloaded 63648271 bytes. 03:17:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:17:33 INFO - mkdir: /builds/slave/test/properties 03:17:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:17:33 INFO - Writing to file /builds/slave/test/properties/build_url 03:17:33 INFO - Contents: 03:17:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:17:35 INFO - Running post-action listener: _resource_record_post_action 03:17:35 INFO - Running post-action listener: set_extra_try_arguments 03:17:35 INFO - ##### 03:17:35 INFO - ##### Finished download-and-extract step (success) 03:17:35 INFO - ##### 03:17:35 INFO - ##### 03:17:35 INFO - ##### Running populate-webroot step. 03:17:35 INFO - ##### 03:17:35 INFO - Running pre-action listener: _resource_record_pre_action 03:17:35 INFO - Running main action method: populate_webroot 03:17:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:17:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:17:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:17:35 INFO - '--tppagecycles', 03:17:35 INFO - '7'], 03:17:35 INFO - 'tests': ['tsvgm']}}, 03:17:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:17:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:17:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:17:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:17:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:17:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:17:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['damp', 'tps']}, 03:17:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'tests': ['damp', 'tps']}, 03:17:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['dromaeo_dom']}, 03:17:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:17:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['a11yr', 03:17:35 INFO - 'ts_paint', 03:17:35 INFO - 'tpaint', 03:17:35 INFO - 'sessionrestore', 03:17:35 INFO - 'sessionrestore_no_auto_restore', 03:17:35 INFO - 'tabpaint']}, 03:17:35 INFO - 'other-e10s': {'tests': ['a11yr', 03:17:35 INFO - 'ts_paint', 03:17:35 INFO - 'tpaint', 03:17:35 INFO - 'sessionrestore', 03:17:35 INFO - 'sessionrestore_no_auto_restore', 03:17:35 INFO - 'tabpaint']}, 03:17:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:17:35 INFO - 'ts_paint', 03:17:35 INFO - 'tpaint', 03:17:35 INFO - 'sessionrestore', 03:17:35 INFO - 'sessionrestore_no_auto_restore', 03:17:35 INFO - 'tabpaint']}, 03:17:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:17:35 INFO - 'ts_paint', 03:17:35 INFO - 'tpaint', 03:17:35 INFO - 'sessionrestore', 03:17:35 INFO - 'sessionrestore_no_auto_restore', 03:17:35 INFO - 'tabpaint']}, 03:17:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['a11yr', 03:17:35 INFO - 'ts_paint', 03:17:35 INFO - 'tpaint', 03:17:35 INFO - 'sessionrestore', 03:17:35 INFO - 'sessionrestore_no_auto_restore', 03:17:35 INFO - 'tabpaint']}, 03:17:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['a11yr', 03:17:35 INFO - 'ts_paint', 03:17:35 INFO - 'tpaint', 03:17:35 INFO - 'sessionrestore', 03:17:35 INFO - 'sessionrestore_no_auto_restore', 03:17:35 INFO - 'tabpaint']}, 03:17:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['tsvgx', 03:17:35 INFO - 'tsvgr_opacity', 03:17:35 INFO - 'tart', 03:17:35 INFO - 'tscrollx', 03:17:35 INFO - 'cart']}, 03:17:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:17:35 INFO - 'tsvgr_opacity', 03:17:35 INFO - 'tart', 03:17:35 INFO - 'tscrollx', 03:17:35 INFO - 'cart']}, 03:17:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'talos_options': ['--disable-e10s'], 03:17:35 INFO - 'tests': ['tp5o']}, 03:17:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'tests': ['tp5o']}, 03:17:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'talos_options': ['--disable-e10s', 03:17:35 INFO - '--xperf_path', 03:17:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:17:35 INFO - 'tests': ['tp5n']}, 03:17:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:35 INFO - 'talos_options': ['--xperf_path', 03:17:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:17:35 INFO - 'tests': ['tp5n']}}, 03:17:35 INFO - 'talos.zip': {'path': '', 03:17:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:17:35 INFO - Running post-action listener: _resource_record_post_action 03:17:35 INFO - ##### 03:17:35 INFO - ##### Finished populate-webroot step (success) 03:17:35 INFO - ##### 03:17:35 INFO - ##### 03:17:35 INFO - ##### Running create-virtualenv step. 03:17:35 INFO - ##### 03:17:35 INFO - Running pre-action listener: _resource_record_pre_action 03:17:35 INFO - Running main action method: create_virtualenv 03:17:35 INFO - Creating virtualenv /builds/slave/test/build/venv 03:17:35 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:17:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:17:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:17:35 INFO - Using real prefix '/usr' 03:17:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:17:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:17:37 INFO - Installing pip.................done. 03:17:37 INFO - Return code: 0 03:17:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:37 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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:37 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:17:37 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:17:37 INFO - Using env: {'DISPLAY': ':0', 03:17:37 INFO - 'HOME': '/home/cltbld', 03:17:37 INFO - 'LANG': 'en_US.UTF-8', 03:17:37 INFO - 'LANGUAGE': 'en_US:en', 03:17:37 INFO - 'LOGNAME': 'cltbld', 03:17:37 INFO - 'MAIL': '/var/mail/cltbld', 03:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:37 INFO - 'MOZ_NO_REMOTE': '1', 03:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:37 INFO - 'NO_EM_RESTART': '1', 03:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:37 INFO - 'PWD': '/builds/slave/test', 03:17:37 INFO - 'SHELL': '/bin/bash', 03:17:37 INFO - 'SHLVL': '1', 03:17:37 INFO - 'TERM': 'linux', 03:17:37 INFO - 'TMOUT': '86400', 03:17:37 INFO - 'USER': 'cltbld', 03:17:37 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:37 INFO - '_': '/tools/buildbot/bin/python'} 03:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:37 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:17:37 INFO - Cleaning up... 03:17:37 INFO - Return code: 0 03:17:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:37 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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:37 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:17:37 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:17:37 INFO - Using env: {'DISPLAY': ':0', 03:17:37 INFO - 'HOME': '/home/cltbld', 03:17:37 INFO - 'LANG': 'en_US.UTF-8', 03:17:37 INFO - 'LANGUAGE': 'en_US:en', 03:17:37 INFO - 'LOGNAME': 'cltbld', 03:17:37 INFO - 'MAIL': '/var/mail/cltbld', 03:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:37 INFO - 'MOZ_NO_REMOTE': '1', 03:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:37 INFO - 'NO_EM_RESTART': '1', 03:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:37 INFO - 'PWD': '/builds/slave/test', 03:17:37 INFO - 'SHELL': '/bin/bash', 03:17:37 INFO - 'SHLVL': '1', 03:17:37 INFO - 'TERM': 'linux', 03:17:37 INFO - 'TMOUT': '86400', 03:17:37 INFO - 'USER': 'cltbld', 03:17:37 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:37 INFO - '_': '/tools/buildbot/bin/python'} 03:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:38 INFO - Downloading/unpacking psutil>=3.1.1 03:17:38 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:17:38 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:17:38 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:17:38 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:17:38 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:17:38 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:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:17:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:17:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:17:40 INFO - Installing collected packages: psutil 03:17:40 INFO - Running setup.py install for psutil 03:17:40 INFO - building 'psutil._psutil_linux' extension 03:17:40 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:17:40 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:17:40 INFO - building 'psutil._psutil_posix' extension 03:17:40 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:17:40 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:17:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:17:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:17:40 INFO - Successfully installed psutil 03:17:40 INFO - Cleaning up... 03:17:40 INFO - Return code: 0 03:17:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:17:40 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:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:40 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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:40 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:17:40 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:17:40 INFO - Using env: {'DISPLAY': ':0', 03:17:40 INFO - 'HOME': '/home/cltbld', 03:17:40 INFO - 'LANG': 'en_US.UTF-8', 03:17:40 INFO - 'LANGUAGE': 'en_US:en', 03:17:40 INFO - 'LOGNAME': 'cltbld', 03:17:40 INFO - 'MAIL': '/var/mail/cltbld', 03:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:40 INFO - 'MOZ_NO_REMOTE': '1', 03:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:40 INFO - 'NO_EM_RESTART': '1', 03:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:40 INFO - 'PWD': '/builds/slave/test', 03:17:40 INFO - 'SHELL': '/bin/bash', 03:17:40 INFO - 'SHLVL': '1', 03:17:40 INFO - 'TERM': 'linux', 03:17:40 INFO - 'TMOUT': '86400', 03:17:40 INFO - 'USER': 'cltbld', 03:17:40 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:40 INFO - '_': '/tools/buildbot/bin/python'} 03:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:17: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:17: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:17: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:17: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:17: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:17: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:17:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:17:42 INFO - Installing collected packages: mozsystemmonitor 03:17:42 INFO - Running setup.py install for mozsystemmonitor 03:17:42 INFO - Successfully installed mozsystemmonitor 03:17:42 INFO - Cleaning up... 03:17:42 INFO - Return code: 0 03:17:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:17:42 INFO - Using env: {'DISPLAY': ':0', 03:17:42 INFO - 'HOME': '/home/cltbld', 03:17:42 INFO - 'LANG': 'en_US.UTF-8', 03:17:42 INFO - 'LANGUAGE': 'en_US:en', 03:17:42 INFO - 'LOGNAME': 'cltbld', 03:17:42 INFO - 'MAIL': '/var/mail/cltbld', 03:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:42 INFO - 'MOZ_NO_REMOTE': '1', 03:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:42 INFO - 'NO_EM_RESTART': '1', 03:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:42 INFO - 'PWD': '/builds/slave/test', 03:17:42 INFO - 'SHELL': '/bin/bash', 03:17:42 INFO - 'SHLVL': '1', 03:17:42 INFO - 'TERM': 'linux', 03:17:42 INFO - 'TMOUT': '86400', 03:17:42 INFO - 'USER': 'cltbld', 03:17:42 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:42 INFO - '_': '/tools/buildbot/bin/python'} 03:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:43 INFO - Downloading/unpacking jsonschema==2.5.1 03:17: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:17: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:17: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:17: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:17: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:17: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:17:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:17:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:45 INFO - Downloading functools32-3.2.3-2.tar.gz 03:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:17:45 INFO - warning: no files found matching '*.txt' 03:17:45 INFO - no previously-included directories found matching 'build' 03:17:45 INFO - no previously-included directories found matching 'dist' 03:17:45 INFO - no previously-included directories found matching '.git*' 03:17:45 INFO - Installing collected packages: jsonschema, functools32 03:17:45 INFO - Running setup.py install for functools32 03:17:45 INFO - warning: no files found matching '*.txt' 03:17:45 INFO - no previously-included directories found matching 'build' 03:17:45 INFO - no previously-included directories found matching 'dist' 03:17:45 INFO - no previously-included directories found matching '.git*' 03:17:45 INFO - Successfully installed jsonschema functools32 03:17:45 INFO - Cleaning up... 03:17:45 INFO - Return code: 0 03:17:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:17:45 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:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:45 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:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:45 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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:45 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:17:45 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:17:45 INFO - Using env: {'DISPLAY': ':0', 03:17:45 INFO - 'HOME': '/home/cltbld', 03:17:45 INFO - 'LANG': 'en_US.UTF-8', 03:17:45 INFO - 'LANGUAGE': 'en_US:en', 03:17:45 INFO - 'LOGNAME': 'cltbld', 03:17:45 INFO - 'MAIL': '/var/mail/cltbld', 03:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:45 INFO - 'MOZ_NO_REMOTE': '1', 03:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:45 INFO - 'NO_EM_RESTART': '1', 03:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:45 INFO - 'PWD': '/builds/slave/test', 03:17:45 INFO - 'SHELL': '/bin/bash', 03:17:45 INFO - 'SHLVL': '1', 03:17:45 INFO - 'TERM': 'linux', 03:17:45 INFO - 'TMOUT': '86400', 03:17:45 INFO - 'USER': 'cltbld', 03:17:45 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:45 INFO - '_': '/tools/buildbot/bin/python'} 03:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:17:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:17:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:48 INFO - Downloading docopt-0.6.1.tar.gz 03:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:17:48 INFO - Installing collected packages: blobuploader, requests, docopt 03:17:48 INFO - Running setup.py install for blobuploader 03:17:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:17:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:17:48 INFO - Running setup.py install for requests 03:17:48 INFO - Running setup.py install for docopt 03:17:48 INFO - Successfully installed blobuploader requests docopt 03:17:48 INFO - Cleaning up... 03:17:48 INFO - Return code: 0 03:17:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:48 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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:48 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:17:48 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:17:49 INFO - Using env: {'DISPLAY': ':0', 03:17:49 INFO - 'HOME': '/home/cltbld', 03:17:49 INFO - 'LANG': 'en_US.UTF-8', 03:17:49 INFO - 'LANGUAGE': 'en_US:en', 03:17:49 INFO - 'LOGNAME': 'cltbld', 03:17:49 INFO - 'MAIL': '/var/mail/cltbld', 03:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:49 INFO - 'MOZ_NO_REMOTE': '1', 03:17:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:49 INFO - 'NO_EM_RESTART': '1', 03:17:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:49 INFO - 'PWD': '/builds/slave/test', 03:17:49 INFO - 'SHELL': '/bin/bash', 03:17:49 INFO - 'SHLVL': '1', 03:17:49 INFO - 'TERM': 'linux', 03:17:49 INFO - 'TMOUT': '86400', 03:17:49 INFO - 'USER': 'cltbld', 03:17:49 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:49 INFO - '_': '/tools/buildbot/bin/python'} 03:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:49 INFO - Running setup.py (path:/tmp/pip-Gwqyp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:49 INFO - Running setup.py (path:/tmp/pip-8PPgn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:49 INFO - Running setup.py (path:/tmp/pip-cpReWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:49 INFO - Running setup.py (path:/tmp/pip-FOWuTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:49 INFO - Running setup.py (path:/tmp/pip-NhRmQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:49 INFO - Running setup.py (path:/tmp/pip-1WEiWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:49 INFO - Running setup.py (path:/tmp/pip-n22g7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:49 INFO - Running setup.py (path:/tmp/pip-j3GtnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:49 INFO - Running setup.py (path:/tmp/pip-tl0ba0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:49 INFO - Running setup.py (path:/tmp/pip-pelBDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:49 INFO - Running setup.py (path:/tmp/pip-lxnZx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:50 INFO - Running setup.py (path:/tmp/pip-zoCYi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:50 INFO - Running setup.py (path:/tmp/pip-sdx14u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:50 INFO - Running setup.py (path:/tmp/pip-HploXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:50 INFO - Running setup.py (path:/tmp/pip-lZqHJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:50 INFO - Running setup.py (path:/tmp/pip-VgfdWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:50 INFO - Running setup.py (path:/tmp/pip-zueOT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:17:50 INFO - Running setup.py install for manifestparser 03:17:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:17:50 INFO - Running setup.py install for mozcrash 03:17:50 INFO - Running setup.py install for mozdebug 03:17:50 INFO - Running setup.py install for mozdevice 03:17:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:17:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:17:50 INFO - Running setup.py install for mozfile 03:17:50 INFO - Running setup.py install for mozhttpd 03:17:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for mozinfo 03:17:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for mozInstall 03:17:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for mozleak 03:17:51 INFO - Running setup.py install for mozlog 03:17:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for moznetwork 03:17:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for mozprocess 03:17:51 INFO - Running setup.py install for mozprofile 03:17:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for mozrunner 03:17:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:17:51 INFO - Running setup.py install for mozscreenshot 03:17:52 INFO - Running setup.py install for moztest 03:17:52 INFO - Running setup.py install for mozversion 03:17:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:17:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:17:52 INFO - Cleaning up... 03:17:52 INFO - Return code: 0 03:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:52 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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:52 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:17:52 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:17:52 INFO - Using env: {'DISPLAY': ':0', 03:17:52 INFO - 'HOME': '/home/cltbld', 03:17:52 INFO - 'LANG': 'en_US.UTF-8', 03:17:52 INFO - 'LANGUAGE': 'en_US:en', 03:17:52 INFO - 'LOGNAME': 'cltbld', 03:17:52 INFO - 'MAIL': '/var/mail/cltbld', 03:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:52 INFO - 'MOZ_NO_REMOTE': '1', 03:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:52 INFO - 'NO_EM_RESTART': '1', 03:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:52 INFO - 'PWD': '/builds/slave/test', 03:17:52 INFO - 'SHELL': '/bin/bash', 03:17:52 INFO - 'SHLVL': '1', 03:17:52 INFO - 'TERM': 'linux', 03:17:52 INFO - 'TMOUT': '86400', 03:17:52 INFO - 'USER': 'cltbld', 03:17:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:52 INFO - '_': '/tools/buildbot/bin/python'} 03:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:52 INFO - Running setup.py (path:/tmp/pip-y4uKu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:52 INFO - Running setup.py (path:/tmp/pip-uN3SUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:52 INFO - Running setup.py (path:/tmp/pip-vGjLci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:52 INFO - Running setup.py (path:/tmp/pip-ZqOJ8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:52 INFO - Running setup.py (path:/tmp/pip-Evk20X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:52 INFO - Running setup.py (path:/tmp/pip-Nq2J4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:52 INFO - Running setup.py (path:/tmp/pip-ffdNN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:53 INFO - Running setup.py (path:/tmp/pip-rBNnnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:53 INFO - Running setup.py (path:/tmp/pip-9KBVfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:53 INFO - Running setup.py (path:/tmp/pip-1PRFsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:53 INFO - Running setup.py (path:/tmp/pip-mMFxmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:53 INFO - Running setup.py (path:/tmp/pip-czYiLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:53 INFO - Running setup.py (path:/tmp/pip-tZZrhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:53 INFO - Running setup.py (path:/tmp/pip-TjZI7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:53 INFO - Running setup.py (path:/tmp/pip-n6rSJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:53 INFO - Running setup.py (path:/tmp/pip-l1HbuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:53 INFO - Running setup.py (path:/tmp/pip-sNa9m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:55 INFO - Downloading blessings-1.6.tar.gz 03:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:17:55 INFO - Installing collected packages: blessings 03:17:55 INFO - Running setup.py install for blessings 03:17:55 INFO - Successfully installed blessings 03:17:55 INFO - Cleaning up... 03:17:55 INFO - Return code: 0 03:17:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:56 INFO - Reading from file tmpfile_stdout 03:17:56 INFO - Current package versions: 03:17:56 INFO - argparse == 1.2.1 03:17:56 INFO - blessings == 1.6 03:17:56 INFO - blobuploader == 1.2.4 03:17:56 INFO - docopt == 0.6.1 03:17:56 INFO - functools32 == 3.2.3-2 03:17:56 INFO - jsonschema == 2.5.1 03:17:56 INFO - manifestparser == 1.1 03:17:56 INFO - mozInstall == 1.12 03:17:56 INFO - mozcrash == 0.17 03:17:56 INFO - mozdebug == 0.1 03:17:56 INFO - mozdevice == 0.48 03:17:56 INFO - mozfile == 1.2 03:17:56 INFO - mozhttpd == 0.7 03:17:56 INFO - mozinfo == 0.9 03:17:56 INFO - mozleak == 0.1 03:17:56 INFO - mozlog == 3.2 03:17:56 INFO - moznetwork == 0.27 03:17:56 INFO - mozprocess == 0.23 03:17:56 INFO - mozprofile == 0.28 03:17:56 INFO - mozrunner == 6.12 03:17:56 INFO - mozscreenshot == 0.1 03:17:56 INFO - mozsystemmonitor == 0.3 03:17:56 INFO - moztest == 0.7 03:17:56 INFO - mozversion == 1.4 03:17:56 INFO - psutil == 3.1.1 03:17:56 INFO - requests == 1.2.3 03:17:56 INFO - wsgiref == 0.1.2 03:17:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:56 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:17:56 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:17:56 INFO - Using env: {'DISPLAY': ':0', 03:17:56 INFO - 'HOME': '/home/cltbld', 03:17:56 INFO - 'LANG': 'en_US.UTF-8', 03:17:56 INFO - 'LANGUAGE': 'en_US:en', 03:17:56 INFO - 'LOGNAME': 'cltbld', 03:17:56 INFO - 'MAIL': '/var/mail/cltbld', 03:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:56 INFO - 'MOZ_NO_REMOTE': '1', 03:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:56 INFO - 'NO_EM_RESTART': '1', 03:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:56 INFO - 'PWD': '/builds/slave/test', 03:17:56 INFO - 'SHELL': '/bin/bash', 03:17:56 INFO - 'SHLVL': '1', 03:17:56 INFO - 'TERM': 'linux', 03:17:56 INFO - 'TMOUT': '86400', 03:17:56 INFO - 'USER': 'cltbld', 03:17:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:56 INFO - '_': '/tools/buildbot/bin/python'} 03:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 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:17:56 INFO - Cleaning up... 03:17:56 INFO - Return code: 0 03:17:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:17:56 INFO - Using env: {'DISPLAY': ':0', 03:17:56 INFO - 'HOME': '/home/cltbld', 03:17:56 INFO - 'LANG': 'en_US.UTF-8', 03:17:56 INFO - 'LANGUAGE': 'en_US:en', 03:17:56 INFO - 'LOGNAME': 'cltbld', 03:17:56 INFO - 'MAIL': '/var/mail/cltbld', 03:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:56 INFO - 'MOZ_NO_REMOTE': '1', 03:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:56 INFO - 'NO_EM_RESTART': '1', 03:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:56 INFO - 'PWD': '/builds/slave/test', 03:17:56 INFO - 'SHELL': '/bin/bash', 03:17:56 INFO - 'SHLVL': '1', 03:17:56 INFO - 'TERM': 'linux', 03:17:56 INFO - 'TMOUT': '86400', 03:17:56 INFO - 'USER': 'cltbld', 03:17:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:56 INFO - '_': '/tools/buildbot/bin/python'} 03:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:17:56 INFO - Cleaning up... 03:17:56 INFO - Return code: 0 03:17:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2191ca4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', '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:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:17:56 INFO - Using env: {'DISPLAY': ':0', 03:17:56 INFO - 'HOME': '/home/cltbld', 03:17:56 INFO - 'LANG': 'en_US.UTF-8', 03:17:56 INFO - 'LANGUAGE': 'en_US:en', 03:17:56 INFO - 'LOGNAME': 'cltbld', 03:17:56 INFO - 'MAIL': '/var/mail/cltbld', 03:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:56 INFO - 'MOZ_NO_REMOTE': '1', 03:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:56 INFO - 'NO_EM_RESTART': '1', 03:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:56 INFO - 'PWD': '/builds/slave/test', 03:17:56 INFO - 'SHELL': '/bin/bash', 03:17:56 INFO - 'SHLVL': '1', 03:17:56 INFO - 'TERM': 'linux', 03:17:56 INFO - 'TMOUT': '86400', 03:17:56 INFO - 'USER': 'cltbld', 03:17:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:56 INFO - '_': '/tools/buildbot/bin/python'} 03:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:56 INFO - Downloading/unpacking flake8 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:17:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:17:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:17:59 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:17:59 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:17:59 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:17:59 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:17:59 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:17:59 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:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:17:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:17:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:17:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:17:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:17:59 INFO - no previously-included directories found matching 'docs/_build' 03:17:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:17:59 INFO - Running setup.py install for pep8 03:17:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:17:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:17:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:17:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:17:59 INFO - no previously-included directories found matching 'docs/_build' 03:17:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:17:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:17:59 INFO - Cleaning up... 03:17:59 INFO - Return code: 0 03:17:59 INFO - Running post-action listener: _resource_record_post_action 03:17:59 INFO - Running post-action listener: _start_resource_monitoring 03:17:59 INFO - Starting resource monitoring. 03:17:59 INFO - ##### 03:17:59 INFO - ##### Finished create-virtualenv step (success) 03:17:59 INFO - ##### 03:17:59 INFO - ##### 03:17:59 INFO - ##### Running install step. 03:17:59 INFO - ##### 03:17:59 INFO - Running pre-action listener: _resource_record_pre_action 03:17:59 INFO - Running main action method: install 03:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:59 INFO - Reading from file tmpfile_stdout 03:17:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:59 INFO - Reading from file tmpfile_stdout 03:17:59 INFO - Output received: 03:17:59 INFO - Usage: mozinstall [options] installer 03:17:59 INFO - Options: 03:17:59 INFO - -h, --help show this help message and exit 03:17:59 INFO - -d DEST, --destination=DEST 03:17:59 INFO - Directory to install application into. [default: 03:17:59 INFO - "/builds/slave/test"] 03:17:59 INFO - --app=APP Application being installed. [default: firefox] 03:17:59 INFO - mkdir: /builds/slave/test/build/application 03:17:59 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:17:59 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:18:15 INFO - Reading from file tmpfile_stdout 03:18:15 INFO - Output received: 03:18:15 INFO - /builds/slave/test/build/application/firefox/firefox 03:18:15 INFO - Running post-action listener: _resource_record_post_action 03:18:15 INFO - ##### 03:18:15 INFO - ##### Finished install step (success) 03:18:15 INFO - ##### 03:18:15 INFO - ##### 03:18:15 INFO - ##### Running run-tests step. 03:18:15 INFO - ##### 03:18:15 INFO - Running pre-action listener: _resource_record_pre_action 03:18:15 INFO - Running main action method: run_tests 03:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:18:15 INFO - Python 2.7.3 03:18:15 INFO - Return code: 0 03:18:15 INFO - grabbing minidump binary from tooltool 03:18: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:18:15 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 0x118b1c0>, '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 0x118c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118c550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:18:15 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:18:15 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:18:15 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:18:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:18:15 INFO - Return code: 0 03:18:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:18:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:18:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:18:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:18:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:18:16 INFO - Return code: 0 03:18:16 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/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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:18:16 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/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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:18:16 INFO - Using env: {'DISPLAY': ':0', 03:18:16 INFO - 'HOME': '/home/cltbld', 03:18:16 INFO - 'LANG': 'en_US.UTF-8', 03:18:16 INFO - 'LANGUAGE': 'en_US:en', 03:18:16 INFO - 'LOGNAME': 'cltbld', 03:18:16 INFO - 'MAIL': '/var/mail/cltbld', 03:18:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:18:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:16 INFO - 'MOZ_NO_REMOTE': '1', 03:18:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:16 INFO - 'NO_EM_RESTART': '1', 03:18:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:16 INFO - 'PWD': '/builds/slave/test', 03:18:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:18:16 INFO - 'SHELL': '/bin/bash', 03:18:16 INFO - 'SHLVL': '1', 03:18:16 INFO - 'TERM': 'linux', 03:18:16 INFO - 'TMOUT': '86400', 03:18:16 INFO - 'USER': 'cltbld', 03:18:16 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857', 03:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:16 INFO - '_': '/tools/buildbot/bin/python'} 03:18:16 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/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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:18:17 INFO - mozversion application_buildid: 20160729170826 03:18:17 INFO - mozversion application_changeset: 589e1f984537e280ff84591e94afdc3c5bfe2710 03:18:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:18:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:18:17 INFO - mozversion application_name: Firefox 03:18:17 INFO - mozversion application_remotingname: firefox-dev 03:18:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:18:17 INFO - mozversion application_vendor: Mozilla 03:18:17 INFO - mozversion application_version: 49.0a2 03:18:17 INFO - mozversion platform_buildid: 20160729170826 03:18:17 INFO - mozversion platform_changeset: 589e1f984537e280ff84591e94afdc3c5bfe2710 03:18:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:18:17 INFO - mozversion platform_version: 49.0a2 03:18:17 INFO - using testdate: 1469873897 03:18:17 INFO - actual date: 1469873897 03:18:17 INFO - starting webserver on 'localhost:47390' 03:18:17 INFO - SUITE-START | Running 2 tests 03:18:17 INFO - TEST-START | dromaeo_css 03:18:17 INFO - operating with platform_type : linux_ 03:18:17 INFO - Initialising browser for dromaeo_css test... 03:18:17 INFO - TEST-INFO | started process 3471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMlYSCZ/profile http://localhost:47390/getInfo.html) 03:18:21 INFO - PROCESS | 3471 | __metrics Screen width/height:1600/1200 03:18:21 INFO - PROCESS | 3471 | colorDepth:24 03:18:21 INFO - PROCESS | 3471 | Browser inner width/height: 1024/697 03:18:21 INFO - PROCESS | 3471 | __metrics 03:18:22 INFO - PROCESS | 3471 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:18:22 ERROR - PROCESS | 3471 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:18:23 INFO - TEST-INFO | 3471: exit 0 03:18:23 INFO - Browser initialized. 03:18:23 INFO - Running cycle 1/1 for dromaeo_css test... 03:18:23 INFO - TEST-INFO | started process 3563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMlYSCZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:18:34 INFO - PROCESS | 3563 | RSS: Main: 145432576 03:18:34 INFO - PROCESS | 3563 | 03:19:25 INFO - PROCESS | 3563 | 1469873965857 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:21:33 INFO - PROCESS | 3563 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47390/tests/dromaeo/cssquery-ext.html) 03:21:34 INFO - PROCESS | 3563 | RSS: Main: 254017536 03:21:34 INFO - PROCESS | 3563 | 03:24:37 INFO - PROCESS | 3563 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-ext.html (next: http://localhost:47390/tests/dromaeo/cssquery-jquery.html) 03:24:37 INFO - PROCESS | 3563 | RSS: Main: 228765696 03:24:37 INFO - PROCESS | 3563 | 03:27:39 INFO - PROCESS | 3563 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47390/tests/dromaeo/cssquery-mootools.html) 03:27:40 INFO - PROCESS | 3563 | RSS: Main: 222982144 03:27:40 INFO - PROCESS | 3563 | 03:30:42 INFO - PROCESS | 3563 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47390/tests/dromaeo/cssquery-prototype.html) 03:30:42 INFO - PROCESS | 3563 | RSS: Main: 222138368 03:30:42 INFO - PROCESS | 3563 | 03:33:45 INFO - PROCESS | 3563 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47390/tests/dromaeo/cssquery-yui.html) 03:33:45 INFO - PROCESS | 3563 | RSS: Main: 210247680 03:33:45 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | Cycle 1(1): loaded http://localhost:47390/tests/dromaeo/cssquery-yui.html (next: http://localhost:47390/tests/dromaeo/cssquery-dojo.html) 03:36:48 INFO - PROCESS | 3563 | RSS: Main: 262582272 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | __start_tp_report 03:36:48 INFO - PROCESS | 3563 | _x_x_mozilla_page_load 03:36:48 INFO - PROCESS | 3563 | _x_x_mozilla_page_load_details 03:36:48 INFO - PROCESS | 3563 | |i|pagename|runs| 03:36:48 INFO - PROCESS | 3563 | |0;dojo.html;3280.9179770505734;3292.12758618966;3302.7522935779816;3333.849976751046;3337.282484265885;5319.468053194679;5425.321834770653;5447.319085114361;5450;5458.6724796512235;5416.702081385522;5435.673859568419;5444.564434845212;5463.235147079412;5478.780848766055;4903.0929278083495;4941.234108713146;4950.777215025332;4986.9251961220425;5049.798008079691;4829.7826597803005;4943.9617680287;4994.950050499503;4998.450170481244;5001.649884508086;4881.438634557036;4904.681195722284;4938.2098864181735;4939.3578834751615;4977.253411988195;5033.622478314104;5052.191649336125;5057.393112826466;5061.316722242487;5075.9746201268945;4844.806207751686;4865.902681946377;4918.877028074291;4971.477994810565;4998.950220453709;5271.446498117681;5317.73411329432;5332.493413125747;5345.1447768356875;5386.2997810284805;5851.590388672795;5873.412658734135;5880.794172203952;5888.440598143169;5895.086261629454;5818.243628328332;5860.882782344372;5863.794767183171;5893.44012318829;5927;5493.587980901409;5533.08704063829;5535.474129957688;5535.944640553543;5541.445855414467;5752.539796816244;6007.489363404072;6017.1275165100815;6029.547783916224;6030.939690603125;5690.971545142331;5721.313442386941;5738.741756620962;5796.507296879812;5805.564582656318;5853.502452291517;5857.912131318034;5859.062549992066;5870.530357571384;5882.088276317178;5587.357453892773;5710.600257981984;5839.649621022752;5843.035899076604;5847.356790753014;5771.394003629643;5856.180134781134;5859.589828711949;5864.179014937912;5902.3507414184405;4481.081378317437;4490.842820501267;4492.415985921809;4494.37078808967;4516.751578663205;3962.0491082140657;3971.3050216212623;3990.82041308136;4030.8387664493093;4078.857239996586;5532.30846144232;5553.666779993213;5559.610827242135;5564.304461942257;5586.329640443173;5861;5861.3845546217035;5872.530197584183;5886.58793884424;5913.408659134052;5346.224797404432;5500.257465242141;5533.557315414757;5534.916976245359;5545.584081193927;4988.276699878497;5017;5018.573421259161;5021.548060674557;5044.344235249394;6100.206973093558;6111.419415155553;6116.663583502861;6123.601965872204;6124.265088189446;5727.398623144676;5810.360860305447;5822.475977162076;5836.095405212199;5871.794487192928;6096.908546371719;6106.969465152646;6108.419700128481;6113.786017489367;6118.877622447615;5610.18652295423;5754.079347304411;5759.683217423082;5766.769329226784;5770.971145144247;5756.3380211276235;5787.536997040311;5812.33158186797;5826.475617194304;5830.970845145747;5717.828465146053;5723.227364305932;5724.79963201286;5730.627509211888;5735.913961290667;5538.169274608841;5595.160725891149;5703.08750599902;5710.628809127394;5718.285214348207;5533.972330138324;5585.888282234251;5596.020696378199;5599.916001260066;5630.352509461464;5619.8876022480135;5659.377468478382;5732.971335143465;5758.740856661544;5771.336296325976;5473.233747275464;5539.362973258127;5563.944360556343;5588.050031494575;5615 03:36:48 INFO - PROCESS | 3563 | |1;ext.html;596.5704692621314;642.0401499757861;647.3655817299045;651.7067319706133;675.6115233740601;32628.67371326289;46086.3087053694;46823.76588117055;46849;46921.29618055732;47653;48101.99999999991;48138;48255.27617085746;48255.51744482554;8315.168483151698;9117.08829117088;9270;9285.257179425664;10869.293495922779;10535.36787792731;10995.285306455095;11000;11006.779864402708;11008.449577521093;11049.613263535777;11101.666949991513;11103.72240693985;11106.167037472182;11120.165987550967;8451.915480845206;8648.740537783877;10948.233623646347;11033.83449248262;11200.551977920873;10665;10948.726281842937;10990.120790336754;11034.282771619819;11040.779184416268;44321;44844.775776121234;44908;44909.55090449086;44975.32537011948;3679.908002299937;3883.650471457554;4066.6949978751713;4091.161311931047;4404.119176164748;34446.827765861264;35105.64894351075;35209.64790352089;35285.47071793925;35375.11562210965;4636.258198688228;4758.857234282977;4776.5701086902;4782.187028205213;4841.757912104381;372889;373443;373497;374506;374760;68035;68975.65512172507;69048.3095169052;69058.65470672614;69102;40091;41243.58756412457;41417;43281;74288.25711742813;16776.412825551048;16819;16856.831431685772;16922.40771572989;16929.06890121055;12433.440495177738;14591.270436478133;14724.55826325212;14726.410943562352;14736.631584210267;21865;22390.664140037912;22427;22451.775482244968;22649.2072777452;8161.347092232607;8258.25675689191;8271.421000529905;8274.379421543408;8279.875801862976;1092.5575142067476;1094.7263184203948;1111.1388673777888;1162.3897453836803;1200.4057991294364;33426.66573334236;33955.15112122167;33990.32019359599;34166.82916585401;34489;1111.9436535291507;1145.0610499390586;1149.6378640728144;1180.7579446213506;1218.1544959386536;17430.215640296217;17800.643987120446;17913.641727165643;17942.282308707767;17977.910110449626;582363;584716;584785;587805;589915;61554.768904621655;61760;61998.380016200164;62080.689596551725;62179;1304.6673098359859;1422.8932830037788;1425.0452197028017;1435.6697959469382;1462.071584543801;32701.836490817393;33731.83134084314;34306.656933431346;34372.82813585916;34375.65624343824;58514.70742646259;61401;61498.077528837995;65266.34733652603;66113.66943165254;531039;540499;543833;544339;545784;38825;45609.31586026146;47885.28172077491;48073.278900815814;48795.26807097825;51053.489465104874;51771.48228517817;51987;52133.21800172925;53032;42515;44157.33763993607;44177.55822441863;44603.77698111489;45509.772451137535;42373.78813106038;42551.14897702091;42732.35901461419;43215.35176972411;44033;43697.78151109224;43739.12521749484;44042;45103;45218;37411;39069.41395879007;39813.60186398099;40262;40272;28891.277718057216;29861.812278513557;29971.400571988;30129.246768830955;30177.698223017487 03:36:48 INFO - PROCESS | 3563 | |2;jquery.html;413.216953872412;420.8674267605704;425.65947242206227;425.9914801703966;438.8091180336554;17990.550236244086;19112;19234.61530769384;19502.512437189078;19630.312939047137;18655.906720466377;18910.810891891113;19118.808811911877;19854.70217946732;19877.900610496963;5307.840764777063;5673.801416950408;5680.346760122577;5680.772769089232;5684.062129748607;4207.495100587934;5500.3949565547755;5540.113581826909;6022.698865056752;6381.074744162093;6444;6454.644994525299;6458.93541064588;6466.902996455057;6470.4823614110755;6266.717997690069;6403.103565500834;6509.251436084864;6517.152770139899;6520.0871877937125;3822.541295044585;5354.170103633943;5616.045272303718;5623.128415095667;5634.774609015675;23461.41346466335;23650.172243971378;23732.64401033986;23811.404714882094;23817.880910595508;2386.176769014687;2489.9915534208676;2497.8501289922665;2503.9374015649573;2554.4124851284123;14339.569812905629;14585.781213281809;14654;14663;14809.851901481063;1978.3372570189003;2000.4078404846373;2017.9293724719564;2022.6055919095782;2047.754269487656;75521.24478755197;77287.61356193239;77724;78015;78194;10419.635312764018;10713.732156696145;11011.449427528592;11031.83452248201;11109.222354435273;15535.611609709622;15602.843971560365;15626;15626.296816643278;15636.296366663528;8181.631826567819;8263.132371100948;8297.377696672653;8411.579421028924;8429.283510901641;7949.523028618301;8170.918290817134;8414.863993360817;8416.537090460084;8472.364572656952;6830.112085428863;6831.726730930806;6867.175938887365;6937.653117344113;6948.895766563505;1667.132532044881;1840.3282801777523;1955.5893262415052;1985.662437385648;1990.532224927141;1022.6471867205802;1076.924615276923;1094.441834664311;1134.8014097533064;1179.4397661111075;18836.81163188378;19351.225950961805;19624.215031398584;19687.40937771869;19793.604127917362;1059.6926891201465;1060.7719340341864;1083.1767856429012;1090.111559079348;1093.5516438260274;10973.451327433597;11457.541698331974;11508.539658413738;11556.306621602731;11633.825492617618;405567;405896;410615;461107;525438;101289.49355253177;104300.47849760702;104709;107160.46419768008;108291;1045.1951996962296;1066.328213225663;1103.542030057533;1104.5636973395465;1110.4447776112106;17488.125593720517;17490.91254543719;17634.82365176332;17752.28990840352;17969.64060718752;42625.5737442634;42789.35815962701;44282.77858610686;44483.77758111318;44703.88240294019;61760.69119654373;61871;62441.687791560755;62442.375576243656;62894.37105628885;8400.369972251983;8587.613557389808;8631;8672.132786721277;8737.91262087371;12452.501899924264;13295.335233238106;13306.201627902357;13442.395092220679;13697.657558561115;11722.120840937134;11766.235194712317;11770.234934729215;11793.233439826385;11805.527778888749;11434.48544815502;11883.108766842348;11899.167058305835;11936.10479214045;11997.220180688228;11981.64055078349;12300.81548776787;12457;12525.56160534377;12591.24452532851;7821.100573434128;8029.076656184614;8922.55387230649;9005.279577633904;9037.68368107113;12112.636620901387;12169.39153042362;12274.693132671397;12275;12314.13801033919 03:36:48 INFO - PROCESS | 3563 | |3;mootools.html;1369.4795977528538;1558.1819544739008;1576.101622075417;1753.701870681984;1858.1731129647312;8409.621567029482;8827.558622068895;8970.596323165457;8997.505137217451;9295.302852286071;9107.77230569236;9113.863292050624;9167.90832091679;9170.81658366834;9183.49490778007;2294.047970092414;2322.396176993977;2322.988385058081;2325.0699720112007;2325.3953971967326;2348.3072493614427;2386.4988352446007;2421.1889017179265;2423.1761201191593;2426.4783071639595;2222.533268013719;2225.5771403433296;2231.5871563760757;2269.943251418711;2280.1905323610126;2193.8025577698086;2222.4554984029014;2224.4883676754275;2224.810891074261;2231.944201394962;2228.465168359582;2231.866088034707;2232.5646498932715;2234.8659080455063;2236.4632488202715;9005.594748236344;9055.954720226422;9061.13919177677;9069.455832650063;9215.262778977665;5360.544353729938;5589.161625756169;5609.859753506154;5614.354349249807;5621.100623900156;3413.4538473844314;3438.346714124308;3477.4088404971217;3503.4919936609303;3559.501669766235;1015.9085682288555;1037.99833161;1043.964269674131;1048.4233671480656;1049.648367796789;12778.23330600167;12956.222626642431;12971.610851674559;12974.351282435935;12986;4459.152760975404;4496.550344965477;4506.864794056183;4546.568076032838;4560.247559152703;10068.798624027479;10079.445630490394;10090.747731306776;10108.039736225046;10111.140553053074;5243.108671525773;5249.396319423316;5252;5262.71055091966;5262.736863156903;4264.744115353089;4430.113977204572;4564.7717614119165;4802.303665968415;4900.142475066849;3928.862489812843;3959.8020098994934;3997.220541994341;4015.5382131055303;4019.33680942647;1142.3089031136044;1156.9942150289194;1158.611865025226;1167.2937872587133;1168.7779321928806;927.9953600231955;947.2043483473915;996.1234113979651;1030.7989941961396;1044.3316277582353;6312.8421789454715;6351.237851457855;6403.391677790602;6574.934250657528;6723.932760672428;349.704499697755;353.2634457156857;356.2056613751349;359.1756917873487;363.50381728939806;4380.912381752347;4384.846530371486;4389.714668546534;4424.867253982386;4450.488193857748;11648.650540483799;12102.455389507493;12123.393830308625;12164.513419463123;12169.20900141488;11940.641780746591;11957.34234617087;12002.63992080239;12022.879771202175;12031.157818952765;8442.53566053873;8750.956245218733;8771.561421929006;8816.471011739317;8892.110788921185;6337.746490140343;6592.307807680125;6635.734570617315;6647.235567909753;6656.534042617164;10306.072453479223;10336.586536538454;10385.324953877975;10456.529456174643;10521.579136834442;10658.14734821228;10808.297460665031;10896.945515272373;10906.61826836057;10915.454227288445;7586.165521792488;8090.838183236185;8379.203975622306;8408.159184081544;8607.18231767981;9327.207187389147;9386.155246027618;9412.811743765224;9433.198178154933;9449.574769135268;8089.514629122272;8291.917080829115;8421;8472.19514146155;8568.057513673366;8288.046874609498;8315.838697802086;8320.209580089882;8326.70856520019;8396.076431592643;7792;7849.254320839511;7877.921220787948;7885.763427097197;7889.5266284020845;5626.465485778845;5696.857578560569;5771.134329850556;5808.419158084158;5837.883242335214;5262.400293844692;5271.341082364705;5332.786688532416;5444;5445.700486473123 03:36:48 INFO - PROCESS | 3563 | |4;prototype.html;330.69838276887367;337.8513454080205;340.76146697311873;340.787008119925;342.5461263825431;5177.275181474596;5354.22363757255;5373.005993891123;5413.26920865683;5495.368032676233;5330.546903513196;5366.973165134169;5375.247465354853;5392.352917649878;5394.595405344605;3961.148353104082;4132.8966775830695;4150.3566947123245;4177.874663760077;4182.435371224884;3996.5421265921395;4141.233871733723;4160.292750232453;4162.084341444877;4168.249335279175;4084.5915408459214;4108.28105081611;4111.362738775495;4124.1957818225455;4124.958750412503;3983.24318379507;4023.4970628671417;4133.400656904747;4141.7514949103;4159.896002599929;3551.5915669697797;3583.3549961007006;3603.5675718913635;3608.639136086397;3625.9456108158397;5093.516115968977;5133.6919784813035;5158.871028224287;5159.22611608258;5160.56135228506;4457.375967364572;4646.83736069236;4646.86059418218;4672.462666793328;4693.929591056137;5527.977324195037;5528.336599608033;5546.334439867202;5552.194931734876;5555.416681248451;4011.97994010031;4012.4583181270445;4023.3361495353233;4026.1947610478023;4041.0602910603075;5688.744006519675;5809.182546464961;5816.243888294537;5834.737436815353;5835.649861008353;4098.467199264077;4100.507939047334;4105.67154627635;4121.093359460914;4123.577365808791;4145.7719825409895;4184;4189.350650649154;4192.601702838226;4192.664586833046;4721.6694831362165;4754.95245047552;4797.880052998703;4801.399825021872;4817.614590832725;5302.416734159239;5529.419410961791;5591.245181900473;5609.859753506195;5620.522255608237;1405.5221224783622;1414.6984774007938;1416.4759039155579;1431.3415828718905;1437.3819257719072;893.9061398553189;912.717057712098;915.2769312243254;926.8934072581604;934.3366209990846;5270.130428479258;5336.306280183551;5430.5384042356845;5436;5437.8368648940595;5738.8278351649515;5765.509931655856;5765.8558536036935;5778.451047150514;5840;5281.392639846466;5463.262459567987;5476.479734425223;5478.369987451414;5527.336719593674;3022.3955208957855;3054.022712731949;3055.816651000947;3061.525463553153;3087.598612180447;5931.6441013539325;5942.168096466498;5996.580239383201;6010.579259451796;6014.609050411709;5656.32124145097;5768.163616275617;5803.6807975560905;5828.941710582924;5832.941670583325;5921.437463440966;5926.259217597801;5947.524198064145;5952.958232309328;5970.910436343372;5492.588055895744;5738.770449182153;5744.3394009687745;5748.367679555329;5752.424757524214;5663.461971112738;5672.092465205547;5686.203931449518;5701.885962280814;5705.4579814917515;5915.467607915309;5950.256217972754;5959.28488581373;5980.521558275241;5981.641101533922;4016.638502534786;4141.399497072968;4144.772037537964;4194.4966604006495;4199.950012496875;5666.46168613981;5672.2058911751565;5677.318721753416;5702.258706368146;5717.714114294352;5399.325084364455;5578.525825304894;5589.636673616202;5594.244776955222;5603.131514615241;5507.697076660822;5509.641873278224;5512.228288039598;5541.418151094238;5567.721613919207;5280.419153893145;5367.838964831061;5381.542568881689;5396.865078372915;5442.965836491054;5437.184422336523;5564;5569.526590239875;5575.163725441216;5586.217929489793;5441.238226648352;5449.591280654046;5477.260569823182;5511.476409741068;5533.501984821385 03:36:48 INFO - PROCESS | 3563 | |5;yui.html;811.1361400108883;819.9754007379777;882.8322618702446;888.5779254853941;895.9819417562236;16017.359305627775;17066.146692665377;17101.82898171018;17152.313907443702;17155.571110722223;14947.92526037371;15003.099814011168;15016.474423395184;15072.849271507283;15072.924635376836;849.6558893648082;857.995710021449;859.4241857955185;859.7248880358256;860.8235311761106;864.7838040489878;866.9176428239307;867.9869801952975;868;868.1665601022994;741.2772546766886;754.6792613139421;758.8102974256436;759.1194214710937;877.0834477970536;892.3441270666056;892.5983307511646;893.678275820704;896.0994200828159;896.7668406214431;654.8886689262789;668.4552090046597;668.6656671664168;668.8093893240451;668.976585819494;15279.00686455388;15340.92329538345;15347.846521534753;15359.385624575003;15374.692506149815;14816.851831481656;14928.776068358984;14968.47610333644;15123.621909452242;15284.770728439082;12151.574694885725;12250.938745306306;12363.443645035995;13152.145110567879;13996.370163342572;4848.538986819136;4851.668880563974;4936.062148191868;4939.753012349368;4968.503149685039;72972;73391.26608733898;74909;75139;76164.23835761627;12097.879021209676;12129.090318226168;12130;12258.26450412978;12446.81329780036;20078.09648565818;21168.364949051553;21225.469363266042;21250.574988500142;21277.25529606487;9288.396254243453;9338.206252468617;9511.66709165176;9545.379550329206;9595;4969.602431805519;5059.342285502935;5063.063333283354;5127.8718032048755;5157.561607263349;3741.3639681254253;3947.2697550953167;3990.5610382857863;4038.5557588665224;4038.8990275243355;695.3151146121066;712.7184762018974;714.4284572342106;721.7004942948618;723.562244841863;766.3639179481017;794.0986979777878;817.9263866252065;825.5995842016617;838.1953324808252;11493.138014648935;11564.42177891102;11582.69893080468;11853.585124520772;12093.87906120945;752.3830459023658;790.7311514085123;807.6163822184533;814.3688641303038;821.6713314674179;5207.0366982108435;5363.383210930715;5420.457954204548;5449.809256675997;5559.471850174227;76791.61604191943;77416;106354;126559;126832;100797;105625;108352;108672.4566377163;110132;95560;95675;96166;96860;97986.51006744921;9990.800183996134;10338.638147664795;10351.534180962026;10394.116500097576;10437.21720870953;54002;54742.72628636831;54912.725436372566;55110;56979.43020569741;72726.63636681995;82388.17611824044;83445.58277208575;84080;84917.57541212255;12814.679633008876;12923.741525169256;13433.261170635318;13447.394867230802;13688.110272832235;14934.645395357824;14984.400623975354;15080.924595376953;15453.227338633249;15601.29794159243;13838.4464621414;14043.648908777363;14321.64195895111;14724.484643037871;15212.46756363522;13653.86346136526;14001.43994240219;14271.928640356731;14385.712285754016;14791.038582492103;13988.440462381797;14195.929020355245;14237.288135592971;14273.857261427669;14348.569542913729;11539.134564907497;11996.880031199576;12038.638840834788;12188.817167742314;12223.205491643015;11836.79167206756;11975.520979160736;12042.277463352228;12097;12148.635540933787 03:36:48 INFO - PROCESS | 3563 | __end_tp_report 03:36:48 INFO - PROCESS | 3563 | __start_cc_report 03:36:48 INFO - PROCESS | 3563 | _x_x_mozilla_cycle_collect,903 03:36:48 INFO - PROCESS | 3563 | __end_cc_report 03:36:48 INFO - PROCESS | 3563 | __startTimestamp1469875008554__endTimestamp 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | ------- Summary: start ------- 03:36:48 INFO - PROCESS | 3563 | Number of tests: 6 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | [#0] dojo.html Cycles:165 Average:5426.25 Median:5588.05 stddev:599.92 (10.7%) stddev-sans-first:577.67 03:36:48 INFO - PROCESS | 3563 | Values: 3280.9 3292.1 3302.8 3333.8 3337.3 5319.5 5425.3 5447.3 5450.0 5458.7 5416.7 5435.7 5444.6 5463.2 5478.8 4903.1 4941.2 4950.8 4986.9 5049.8 4829.8 4944.0 4995.0 4998.5 5001.6 4881.4 4904.7 4938.2 4939.4 4977.3 5033.6 5052.2 5057.4 5061.3 5076.0 4844.8 4865.9 4918.9 4971.5 4999.0 5271.4 5317.7 5332.5 5345.1 5386.3 5851.6 5873.4 5880.8 5888.4 5895.1 5818.2 5860.9 5863.8 5893.4 5927.0 5493.6 5533.1 5535.5 5535.9 5541.4 5752.5 6007.5 6017.1 6029.5 6030.9 5691.0 5721.3 5738.7 5796.5 5805.6 5853.5 5857.9 5859.1 5870.5 5882.1 5587.4 5710.6 5839.6 5843.0 5847.4 5771.4 5856.2 5859.6 5864.2 5902.4 4481.1 4490.8 4492.4 4494.4 4516.8 3962.0 3971.3 3990.8 4030.8 4078.9 5532.3 5553.7 5559.6 5564.3 5586.3 5861.0 5861.4 5872.5 5886.6 5913.4 5346.2 5500.3 5533.6 5534.9 5545.6 4988.3 5017.0 5018.6 5021.5 5044.3 6100.2 6111.4 6116.7 6123.6 6124.3 5727.4 5810.4 5822.5 5836.1 5871.8 6096.9 6107.0 6108.4 6113.8 6118.9 5610.2 5754.1 5759.7 5766.8 5771.0 5756.3 5787.5 5812.3 5826.5 5831.0 5717.8 5723.2 5724.8 5730.6 5735.9 5538.2 5595.2 5703.1 5710.6 5718.3 5534.0 5585.9 5596.0 5599.9 5630.4 5619.9 5659.4 5733.0 5758.7 5771.3 5473.2 5539.4 5563.9 5588.1 5615.0 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | [#1] ext.html Cycles:180 Average:67374.96 Median:22667.37 stddev:134976.84 (595.5%) stddev-sans-first:135262.37 03:36:48 INFO - PROCESS | 3563 | Values: 596.6 642.0 647.4 651.7 675.6 32628.7 46086.3 46823.8 46849.0 46921.3 47653.0 48102.0 48138.0 48255.3 48255.5 8315.2 9117.1 9270.0 9285.3 10869.3 10535.4 10995.3 11000.0 11006.8 11008.4 11049.6 11101.7 11103.7 11106.2 11120.2 8451.9 8648.7 10948.2 11033.8 11200.6 10665.0 10948.7 10990.1 11034.3 11040.8 44321.0 44844.8 44908.0 44909.6 44975.3 3679.9 3883.7 4066.7 4091.2 4404.1 34446.8 35105.6 35209.6 35285.5 35375.1 4636.3 4758.9 4776.6 4782.2 4841.8 372889.0 373443.0 373497.0 374506.0 374760.0 68035.0 68975.7 69048.3 69058.7 69102.0 40091.0 41243.6 41417.0 43281.0 74288.3 16776.4 16819.0 16856.8 16922.4 16929.1 12433.4 14591.3 14724.6 14726.4 14736.6 21865.0 22390.7 22427.0 22451.8 22649.2 8161.3 8258.3 8271.4 8274.4 8279.9 1092.6 1094.7 1111.1 1162.4 1200.4 33426.7 33955.2 33990.3 34166.8 34489.0 1111.9 1145.1 1149.6 1180.8 1218.2 17430.2 17800.6 17913.6 17942.3 17977.9 582363.0 584716.0 584785.0 587805.0 589915.0 61554.8 61760.0 61998.4 62080.7 62179.0 1304.7 1422.9 1425.0 1435.7 1462.1 32701.8 33731.8 34306.7 34372.8 34375.7 58514.7 61401.0 61498.1 65266.3 66113.7 531039.0 540499.0 543833.0 544339.0 545784.0 38825.0 45609.3 47885.3 48073.3 48795.3 51053.5 51771.5 51987.0 52133.2 53032.0 42515.0 44157.3 44177.6 44603.8 45509.8 42373.8 42551.1 42732.4 43215.4 44033.0 43697.8 43739.1 44042.0 45103.0 45218.0 37411.0 39069.4 39813.6 40262.0 40272.0 28891.3 29861.8 29971.4 30129.2 30177.7 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | [#2] jquery.html Cycles:180 Average:28427.76 Median:1995.47 stddev:73809.80 (3698.9%) stddev-sans-first:73986.88 03:36:48 INFO - PROCESS | 3563 | Values: 413.2 420.9 425.7 426.0 438.8 17990.6 19112.0 19234.6 19502.5 19630.3 18655.9 18910.8 19118.8 19854.7 19877.9 5307.8 5673.8 5680.3 5680.8 5684.1 4207.5 5500.4 5540.1 6022.7 6381.1 6444.0 6454.6 6458.9 6466.9 6470.5 6266.7 6403.1 6509.3 6517.2 6520.1 3822.5 5354.2 5616.0 5623.1 5634.8 23461.4 23650.2 23732.6 23811.4 23817.9 2386.2 2490.0 2497.9 2503.9 2554.4 14339.6 14585.8 14654.0 14663.0 14809.9 1978.3 2000.4 2017.9 2022.6 2047.8 75521.2 77287.6 77724.0 78015.0 78194.0 10419.6 10713.7 11011.4 11031.8 11109.2 15535.6 15602.8 15626.0 15626.3 15636.3 8181.6 8263.1 8297.4 8411.6 8429.3 7949.5 8170.9 8414.9 8416.5 8472.4 6830.1 6831.7 6867.2 6937.7 6948.9 1667.1 1840.3 1955.6 1985.7 1990.5 1022.6 1076.9 1094.4 1134.8 1179.4 18836.8 19351.2 19624.2 19687.4 19793.6 1059.7 1060.8 1083.2 1090.1 1093.6 10973.5 11457.5 11508.5 11556.3 11633.8 405567.0 405896.0 410615.0 461107.0 525438.0 101289.5 104300.5 104709.0 107160.5 108291.0 1045.2 1066.3 1103.5 1104.6 1110.4 17488.1 17490.9 17634.8 17752.3 17969.6 42625.6 42789.4 44282.8 44483.8 44703.9 61760.7 61871.0 62441.7 62442.4 62894.4 8400.4 8587.6 8631.0 8672.1 8737.9 12452.5 13295.3 13306.2 13442.4 13697.7 11722.1 11766.2 11770.2 11793.2 11805.5 11434.5 11883.1 11899.2 11936.1 11997.2 11981.6 12300.8 12457.0 12525.6 12591.2 7821.1 8029.1 8922.6 9005.3 9037.7 12112.6 12169.4 12274.7 12275.0 12314.1 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | [#3] mootools.html Cycles:180 Average:6079.24 Median:4407.29 stddev:3579.46 (81.2%) stddev-sans-first:3572.00 03:36:48 INFO - PROCESS | 3563 | Values: 1369.5 1558.2 1576.1 1753.7 1858.2 8409.6 8827.6 8970.6 8997.5 9295.3 9107.8 9113.9 9167.9 9170.8 9183.5 2294.0 2322.4 2323.0 2325.1 2325.4 2348.3 2386.5 2421.2 2423.2 2426.5 2222.5 2225.6 2231.6 2269.9 2280.2 2193.8 2222.5 2224.5 2224.8 2231.9 2228.5 2231.9 2232.6 2234.9 2236.5 9005.6 9056.0 9061.1 9069.5 9215.3 5360.5 5589.2 5609.9 5614.4 5621.1 3413.5 3438.3 3477.4 3503.5 3559.5 1015.9 1038.0 1044.0 1048.4 1049.6 12778.2 12956.2 12971.6 12974.4 12986.0 4459.2 4496.6 4506.9 4546.6 4560.2 10068.8 10079.4 10090.7 10108.0 10111.1 5243.1 5249.4 5252.0 5262.7 5262.7 4264.7 4430.1 4564.8 4802.3 4900.1 3928.9 3959.8 3997.2 4015.5 4019.3 1142.3 1157.0 1158.6 1167.3 1168.8 928.0 947.2 996.1 1030.8 1044.3 6312.8 6351.2 6403.4 6574.9 6723.9 349.7 353.3 356.2 359.2 363.5 4380.9 4384.8 4389.7 4424.9 4450.5 11648.7 12102.5 12123.4 12164.5 12169.2 11940.6 11957.3 12002.6 12022.9 12031.2 8442.5 8751.0 8771.6 8816.5 8892.1 6337.7 6592.3 6635.7 6647.2 6656.5 10306.1 10336.6 10385.3 10456.5 10521.6 10658.1 10808.3 10896.9 10906.6 10915.5 7586.2 8090.8 8379.2 8408.2 8607.2 9327.2 9386.2 9412.8 9433.2 9449.6 8089.5 8291.9 8421.0 8472.2 8568.1 8288.0 8315.8 8320.2 8326.7 8396.1 7792.0 7849.3 7877.9 7885.8 7889.5 5626.5 5696.9 5771.1 5808.4 5837.9 5262.4 5271.3 5332.8 5444.0 5445.7 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | [#4] prototype.html Cycles:180 Average:4699.70 Median:5398.10 stddev:1386.63 (25.7%) stddev-sans-first:1351.18 03:36:48 INFO - PROCESS | 3563 | Values: 330.7 337.9 340.8 340.8 342.5 5177.3 5354.2 5373.0 5413.3 5495.4 5330.5 5367.0 5375.2 5392.4 5394.6 3961.1 4132.9 4150.4 4177.9 4182.4 3996.5 4141.2 4160.3 4162.1 4168.2 4084.6 4108.3 4111.4 4124.2 4125.0 3983.2 4023.5 4133.4 4141.8 4159.9 3551.6 3583.4 3603.6 3608.6 3625.9 5093.5 5133.7 5158.9 5159.2 5160.6 4457.4 4646.8 4646.9 4672.5 4693.9 5528.0 5528.3 5546.3 5552.2 5555.4 4012.0 4012.5 4023.3 4026.2 4041.1 5688.7 5809.2 5816.2 5834.7 5835.6 4098.5 4100.5 4105.7 4121.1 4123.6 4145.8 4184.0 4189.4 4192.6 4192.7 4721.7 4755.0 4797.9 4801.4 4817.6 5302.4 5529.4 5591.2 5609.9 5620.5 1405.5 1414.7 1416.5 1431.3 1437.4 893.9 912.7 915.3 926.9 934.3 5270.1 5336.3 5430.5 5436.0 5437.8 5738.8 5765.5 5765.9 5778.5 5840.0 5281.4 5463.3 5476.5 5478.4 5527.3 3022.4 3054.0 3055.8 3061.5 3087.6 5931.6 5942.2 5996.6 6010.6 6014.6 5656.3 5768.2 5803.7 5828.9 5832.9 5921.4 5926.3 5947.5 5953.0 5970.9 5492.6 5738.8 5744.3 5748.4 5752.4 5663.5 5672.1 5686.2 5701.9 5705.5 5915.5 5950.3 5959.3 5980.5 5981.6 4016.6 4141.4 4144.8 4194.5 4200.0 5666.5 5672.2 5677.3 5702.3 5717.7 5399.3 5578.5 5589.6 5594.2 5603.1 5507.7 5509.6 5512.2 5541.4 5567.7 5280.4 5367.8 5381.5 5396.9 5443.0 5437.2 5564.0 5569.5 5575.2 5586.2 5441.2 5449.6 5477.3 5511.5 5533.5 03:36:48 INFO - PROCESS | 3563 | 03:36:48 INFO - PROCESS | 3563 | [#5] yui.html Cycles:180 Average:21633.73 Median:4038.73 stddev:30655.80 (759.0%) stddev-sans-first:30701.93 03:36:48 INFO - PROCESS | 3563 | Values: 811.1 820.0 882.8 888.6 896.0 16017.4 17066.1 17101.8 17152.3 17155.6 14947.9 15003.1 15016.5 15072.8 15072.9 849.7 858.0 859.4 859.7 860.8 864.8 866.9 868.0 868.0 868.2 741.3 754.7 758.8 759.1 877.1 892.3 892.6 893.7 896.1 896.8 654.9 668.5 668.7 668.8 669.0 15279.0 15340.9 15347.8 15359.4 15374.7 14816.9 14928.8 14968.5 15123.6 15284.8 12151.6 12250.9 12363.4 13152.1 13996.4 4848.5 4851.7 4936.1 4939.8 4968.5 72972.0 73391.3 74909.0 75139.0 76164.2 12097.9 12129.1 12130.0 12258.3 12446.8 20078.1 21168.4 21225.5 21250.6 21277.3 9288.4 9338.2 9511.7 9545.4 9595.0 4969.6 5059.3 5063.1 5127.9 5157.6 3741.4 3947.3 3990.6 4038.6 4038.9 695.3 712.7 714.4 721.7 723.6 766.4 794.1 817.9 825.6 838.2 11493.1 11564.4 11582.7 11853.6 12093.9 752.4 790.7 807.6 814.4 821.7 5207.0 5363.4 5420.5 5449.8 5559.5 76791.6 77416.0 106354.0 126559.0 126832.0 100797.0 105625.0 108352.0 108672.5 110132.0 95560.0 95675.0 96166.0 96860.0 97986.5 9990.8 10338.6 10351.5 10394.1 10437.2 54002.0 54742.7 54912.7 55110.0 56979.4 72726.6 82388.2 83445.6 84080.0 84917.6 12814.7 12923.7 13433.3 13447.4 13688.1 14934.6 14984.4 15080.9 15453.2 15601.3 13838.4 14043.6 14321.6 14724.5 15212.5 13653.9 14001.4 14271.9 14385.7 14791.0 13988.4 14195.9 14237.3 14273.9 14348.6 11539.1 11996.9 12038.6 12188.8 12223.2 11836.8 11975.5 12042.3 12097.0 12148.6 03:36:48 INFO - PROCESS | 3563 | -------- Summary: end -------- 03:36:48 INFO - PROCESS | 3563 | 03:36:49 INFO - TEST-INFO | 3563: exit 0 03:36:49 INFO - TEST-OK | dromaeo_css | took 1112279ms 03:36:49 INFO - TEST-START | kraken 03:36:49 INFO - operating with platform_type : linux_ 03:36:49 INFO - Initialising browser for kraken test... 03:36:49 INFO - TEST-INFO | started process 3751 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJtk31B/profile http://localhost:47390/getInfo.html) 03:36:53 INFO - PROCESS | 3751 | __metrics Screen width/height:1600/1200 03:36:53 INFO - PROCESS | 3751 | colorDepth:24 03:36:53 INFO - PROCESS | 3751 | Browser inner width/height: 1024/697 03:36:53 INFO - PROCESS | 3751 | __metrics 03:36:54 INFO - PROCESS | 3751 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:36:54 ERROR - PROCESS | 3751 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:36:55 INFO - TEST-INFO | 3751: exit 0 03:36:55 INFO - Browser initialized. 03:36:55 INFO - Running cycle 1/1 for kraken test... 03:36:55 INFO - TEST-INFO | started process 3848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJtk31B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:37:06 INFO - PROCESS | 3848 | RSS: Main: 145231872 03:37:06 INFO - PROCESS | 3848 | 03:37:09 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-beat-detection) 03:37:10 INFO - PROCESS | 3848 | RSS: Main: 194990080 03:37:10 INFO - PROCESS | 3848 | 03:37:12 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-dft) 03:37:13 INFO - PROCESS | 3848 | RSS: Main: 193585152 03:37:13 INFO - PROCESS | 3848 | 03:37:16 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-fft) 03:37:16 INFO - PROCESS | 3848 | RSS: Main: 279879680 03:37:16 INFO - PROCESS | 3848 | 03:37:18 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47390/tests/kraken/driver.html?testName=audio-oscillator) 03:37:19 INFO - PROCESS | 3848 | RSS: Main: 188690432 03:37:19 INFO - PROCESS | 3848 | 03:37:21 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47390/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:37:22 INFO - PROCESS | 3848 | RSS: Main: 189227008 03:37:22 INFO - PROCESS | 3848 | 03:37:25 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47390/tests/kraken/driver.html?testName=imaging-darkroom) 03:37:25 INFO - PROCESS | 3848 | RSS: Main: 224378880 03:37:25 INFO - PROCESS | 3848 | 03:37:29 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47390/tests/kraken/driver.html?testName=imaging-desaturate) 03:37:29 INFO - PROCESS | 3848 | RSS: Main: 224104448 03:37:29 INFO - PROCESS | 3848 | 03:37:32 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47390/tests/kraken/driver.html?testName=json-parse-financial) 03:37:33 INFO - PROCESS | 3848 | RSS: Main: 223707136 03:37:33 INFO - PROCESS | 3848 | 03:37:34 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47390/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:37:35 INFO - PROCESS | 3848 | RSS: Main: 200896512 03:37:35 INFO - PROCESS | 3848 | 03:37:36 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:37:37 INFO - PROCESS | 3848 | RSS: Main: 201707520 03:37:37 INFO - PROCESS | 3848 | 03:37:39 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:37:40 INFO - PROCESS | 3848 | RSS: Main: 208805888 03:37:40 INFO - PROCESS | 3848 | 03:37:42 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:37:43 INFO - PROCESS | 3848 | RSS: Main: 215416832 03:37:43 INFO - PROCESS | 3848 | 03:37:45 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:37:46 INFO - PROCESS | 3848 | RSS: Main: 213958656 03:37:46 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:47390/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47390/tests/kraken/driver.html?testName=ai-astar) 03:37:48 INFO - PROCESS | 3848 | RSS: Main: 255664128 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | __start_tp_report 03:37:48 INFO - PROCESS | 3848 | _x_x_mozilla_page_load 03:37:48 INFO - PROCESS | 3848 | _x_x_mozilla_page_load_details 03:37:48 INFO - PROCESS | 3848 | |i|pagename|runs| 03:37:48 INFO - PROCESS | 3848 | |0;ai-astar;111;104;110;109;108;110;110;110;110;110 03:37:48 INFO - PROCESS | 3848 | |1;audio-beat-detection;154;123;125;191;124;124;146;126;153;124 03:37:48 INFO - PROCESS | 3848 | |2;audio-dft;160;163;161;161;162;161;163;161;161;160 03:37:48 INFO - PROCESS | 3848 | |3;audio-fft;76;76;77;107;76;76;76;76;104;75 03:37:48 INFO - PROCESS | 3848 | |4;audio-oscillator;85;84;116;85;86;85;114;84;84;130 03:37:48 INFO - PROCESS | 3848 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;117 03:37:48 INFO - PROCESS | 3848 | |6;imaging-darkroom;145;146;145;146;145;145;146;146;146;145 03:37:48 INFO - PROCESS | 3848 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;94;95 03:37:48 INFO - PROCESS | 3848 | |8;json-parse-financial;50;51;50;52;51;52;50;53;51;50 03:37:48 INFO - PROCESS | 3848 | |9;json-stringify-tinderbox;62;62;60;60;63;62;61;62;61;61 03:37:48 INFO - PROCESS | 3848 | |10;stanford-crypto-aes;82;79;77;77;82;80;78;77;110;77 03:37:48 INFO - PROCESS | 3848 | |11;stanford-crypto-ccm;121;120;120;116;125;127;119;128;124;123 03:37:48 INFO - PROCESS | 3848 | |12;stanford-crypto-pbkdf2;155;155;157;163;158;154;154;153;152;153 03:37:48 INFO - PROCESS | 3848 | |13;stanford-crypto-sha256-iterative;61;63;62;62;62;62;62;62;60;59 03:37:48 INFO - PROCESS | 3848 | __end_tp_report 03:37:48 INFO - PROCESS | 3848 | __start_cc_report 03:37:48 INFO - PROCESS | 3848 | _x_x_mozilla_cycle_collect,1570 03:37:48 INFO - PROCESS | 3848 | __end_cc_report 03:37:48 INFO - PROCESS | 3848 | __startTimestamp1469875068489__endTimestamp 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | ------- Summary: start ------- 03:37:48 INFO - PROCESS | 3848 | Number of tests: 14 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:1.99 (1.8%) stddev-sans-first:2.00 03:37:48 INFO - PROCESS | 3848 | Values: 111.0 104.0 110.0 109.0 108.0 110.0 110.0 110.0 110.0 110.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:136.00 stddev:22.29 (16.4%) stddev-sans-first:22.97 03:37:48 INFO - PROCESS | 3848 | Values: 154.0 123.0 125.0 191.0 124.0 124.0 146.0 126.0 153.0 124.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 03:37:48 INFO - PROCESS | 3848 | Values: 160.0 163.0 161.0 161.0 162.0 161.0 163.0 161.0 161.0 160.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:12.47 (16.4%) stddev-sans-first:13.04 03:37:48 INFO - PROCESS | 3848 | Values: 76.0 76.0 77.0 107.0 76.0 76.0 76.0 76.0 104.0 75.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:84.50 stddev:17.54 (20.8%) stddev-sans-first:18.21 03:37:48 INFO - PROCESS | 3848 | Values: 85.0 84.0 116.0 85.0 86.0 85.0 114.0 84.0 84.0 130.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:37:48 INFO - PROCESS | 3848 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 03:37:48 INFO - PROCESS | 3848 | Values: 145.0 146.0 145.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:37:48 INFO - PROCESS | 3848 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.05 (2.1%) stddev-sans-first:1.05 03:37:48 INFO - PROCESS | 3848 | Values: 50.0 51.0 50.0 52.0 51.0 52.0 50.0 53.0 51.0 50.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:62.00 stddev:0.97 (1.6%) stddev-sans-first:1.00 03:37:48 INFO - PROCESS | 3848 | Values: 62.0 62.0 60.0 60.0 63.0 62.0 61.0 62.0 61.0 61.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:78.50 stddev:10.07 (12.8%) stddev-sans-first:10.68 03:37:48 INFO - PROCESS | 3848 | Values: 82.0 79.0 77.0 77.0 82.0 80.0 78.0 77.0 110.0 77.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:123.50 stddev:3.77 (3.1%) stddev-sans-first:3.97 03:37:48 INFO - PROCESS | 3848 | Values: 121.0 120.0 120.0 116.0 125.0 127.0 119.0 128.0 124.0 123.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.40 Median:155.00 stddev:3.24 (2.1%) stddev-sans-first:3.43 03:37:48 INFO - PROCESS | 3848 | Values: 155.0 155.0 157.0 163.0 158.0 154.0 154.0 153.0 152.0 153.0 03:37:48 INFO - PROCESS | 3848 | 03:37:48 INFO - PROCESS | 3848 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.18 (1.9%) stddev-sans-first:1.24 03:37:48 INFO - PROCESS | 3848 | Values: 61.0 63.0 62.0 62.0 62.0 62.0 62.0 62.0 60.0 59.0 03:37:48 INFO - PROCESS | 3848 | -------- Summary: end -------- 03:37:48 INFO - PROCESS | 3848 | 03:37:49 INFO - TEST-INFO | 3848: exit 0 03:37:49 INFO - TEST-OK | kraken | took 60108ms 03:37:49 INFO - SUITE-END | took 1172s 03:37:49 INFO - Completed test suite (00:19:32) 03:37:49 INFO - javascript benchmark 03:37:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.9179770505734, 3292.12758618966, 3302.7522935779816, 3333.849976751046, 3337.282484265885, 5319.468053194679, 5425.321834770653, 5447.319085114361, 5450.0, 5458.6724796512235, 5416.702081385522, 5435.673859568419, 5444.564434845212, 5463.235147079412, 5478.780848766055, 4903.0929278083495, 4941.234108713146, 4950.777215025332, 4986.9251961220425, 5049.798008079691, 4829.7826597803005, 4943.9617680287, 4994.950050499503, 4998.450170481244, 5001.649884508086, 4881.438634557036, 4904.681195722284, 4938.2098864181735, 4939.3578834751615, 4977.253411988195, 5033.622478314104, 5052.191649336125, 5057.393112826466, 5061.316722242487, 5075.9746201268945, 4844.806207751686, 4865.902681946377, 4918.877028074291, 4971.477994810565, 4998.950220453709, 5271.446498117681, 5317.73411329432, 5332.493413125747, 5345.1447768356875, 5386.2997810284805, 5851.590388672795, 5873.412658734135, 5880.794172203952, 5888.440598143169, 5895.086261629454, 5818.243628328332, 5860.882782344372, 5863.794767183171, 5893.44012318829, 5927.0, 5493.587980901409, 5533.08704063829, 5535.474129957688, 5535.944640553543, 5541.445855414467, 5752.539796816244, 6007.489363404072, 6017.1275165100815, 6029.547783916224, 6030.939690603125, 5690.971545142331, 5721.313442386941, 5738.741756620962, 5796.507296879812, 5805.564582656318, 5853.502452291517, 5857.912131318034, 5859.062549992066, 5870.530357571384, 5882.088276317178, 5587.357453892773, 5710.600257981984, 5839.649621022752, 5843.035899076604, 5847.356790753014, 5771.394003629643, 5856.180134781134, 5859.589828711949, 5864.179014937912, 5902.3507414184405, 4481.081378317437, 4490.842820501267, 4492.415985921809, 4494.37078808967, 4516.751578663205, 3962.0491082140657, 3971.3050216212623, 3990.82041308136, 4030.8387664493093, 4078.857239996586, 5532.30846144232, 5553.666779993213, 5559.610827242135, 5564.304461942257, 5586.329640443173, 5861.0, 5861.3845546217035, 5872.530197584183, 5886.58793884424, 5913.408659134052, 5346.224797404432, 5500.257465242141, 5533.557315414757, 5534.916976245359, 5545.584081193927, 4988.276699878497, 5017.0, 5018.573421259161, 5021.548060674557, 5044.344235249394, 6100.206973093558, 6111.419415155553, 6116.663583502861, 6123.601965872204, 6124.265088189446, 5727.398623144676, 5810.360860305447, 5822.475977162076, 5836.095405212199, 5871.794487192928, 6096.908546371719, 6106.969465152646, 6108.419700128481, 6113.786017489367, 6118.877622447615, 5610.18652295423, 5754.079347304411, 5759.683217423082, 5766.769329226784, 5770.971145144247, 5756.3380211276235, 5787.536997040311, 5812.33158186797, 5826.475617194304, 5830.970845145747, 5717.828465146053, 5723.227364305932, 5724.79963201286, 5730.627509211888, 5735.913961290667, 5538.169274608841, 5595.160725891149, 5703.08750599902, 5710.628809127394, 5718.285214348207, 5533.972330138324, 5585.888282234251, 5596.020696378199, 5599.916001260066, 5630.352509461464, 5619.8876022480135, 5659.377468478382, 5732.971335143465, 5758.740856661544, 5771.336296325976, 5473.233747275464, 5539.362973258127, 5563.944360556343, 5588.050031494575, 5615.0], "value": 5387.7148437798005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.5704692621314, 642.0401499757861, 647.3655817299045, 651.7067319706133, 675.6115233740601, 32628.67371326289, 46086.3087053694, 46823.76588117055, 46849.0, 46921.29618055732, 47653.0, 48101.99999999991, 48138.0, 48255.27617085746, 48255.51744482554, 8315.168483151698, 9117.08829117088, 9270.0, 9285.257179425664, 10869.293495922779, 10535.36787792731, 10995.285306455095, 11000.0, 11006.779864402708, 11008.449577521093, 11049.613263535777, 11101.666949991513, 11103.72240693985, 11106.167037472182, 11120.165987550967, 8451.915480845206, 8648.740537783877, 10948.233623646347, 11033.83449248262, 11200.551977920873, 10665.0, 10948.726281842937, 10990.120790336754, 11034.282771619819, 11040.779184416268, 44321.0, 44844.775776121234, 44908.0, 44909.55090449086, 44975.32537011948, 3679.908002299937, 3883.650471457554, 4066.6949978751713, 4091.161311931047, 4404.119176164748, 34446.827765861264, 35105.64894351075, 35209.64790352089, 35285.47071793925, 35375.11562210965, 4636.258198688228, 4758.857234282977, 4776.5701086902, 4782.187028205213, 4841.757912104381, 372889.0, 373443.0, 373497.0, 374506.0, 374760.0, 68035.0, 68975.65512172507, 69048.3095169052, 69058.65470672614, 69102.0, 40091.0, 41243.58756412457, 41417.0, 43281.0, 74288.25711742813, 16776.412825551048, 16819.0, 16856.831431685772, 16922.40771572989, 16929.06890121055, 12433.440495177738, 14591.270436478133, 14724.55826325212, 14726.410943562352, 14736.631584210267, 21865.0, 22390.664140037912, 22427.0, 22451.775482244968, 22649.2072777452, 8161.347092232607, 8258.25675689191, 8271.421000529905, 8274.379421543408, 8279.875801862976, 1092.5575142067476, 1094.7263184203948, 1111.1388673777888, 1162.3897453836803, 1200.4057991294364, 33426.66573334236, 33955.15112122167, 33990.32019359599, 34166.82916585401, 34489.0, 1111.9436535291507, 1145.0610499390586, 1149.6378640728144, 1180.7579446213506, 1218.1544959386536, 17430.215640296217, 17800.643987120446, 17913.641727165643, 17942.282308707767, 17977.910110449626, 582363.0, 584716.0, 584785.0, 587805.0, 589915.0, 61554.768904621655, 61760.0, 61998.380016200164, 62080.689596551725, 62179.0, 1304.6673098359859, 1422.8932830037788, 1425.0452197028017, 1435.6697959469382, 1462.071584543801, 32701.836490817393, 33731.83134084314, 34306.656933431346, 34372.82813585916, 34375.65624343824, 58514.70742646259, 61401.0, 61498.077528837995, 65266.34733652603, 66113.66943165254, 531039.0, 540499.0, 543833.0, 544339.0, 545784.0, 38825.0, 45609.31586026146, 47885.28172077491, 48073.278900815814, 48795.26807097825, 51053.489465104874, 51771.48228517817, 51987.0, 52133.21800172925, 53032.0, 42515.0, 44157.33763993607, 44177.55822441863, 44603.77698111489, 45509.772451137535, 42373.78813106038, 42551.14897702091, 42732.35901461419, 43215.35176972411, 44033.0, 43697.78151109224, 43739.12521749484, 44042.0, 45103.0, 45218.0, 37411.0, 39069.41395879007, 39813.60186398099, 40262.0, 40272.0, 28891.277718057216, 29861.812278513557, 29971.400571988, 30129.246768830955, 30177.698223017487], "value": 22228.917892210266, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.216953872412, 420.8674267605704, 425.65947242206227, 425.9914801703966, 438.8091180336554, 17990.550236244086, 19112.0, 19234.61530769384, 19502.512437189078, 19630.312939047137, 18655.906720466377, 18910.810891891113, 19118.808811911877, 19854.70217946732, 19877.900610496963, 5307.840764777063, 5673.801416950408, 5680.346760122577, 5680.772769089232, 5684.062129748607, 4207.495100587934, 5500.3949565547755, 5540.113581826909, 6022.698865056752, 6381.074744162093, 6444.0, 6454.644994525299, 6458.93541064588, 6466.902996455057, 6470.4823614110755, 6266.717997690069, 6403.103565500834, 6509.251436084864, 6517.152770139899, 6520.0871877937125, 3822.541295044585, 5354.170103633943, 5616.045272303718, 5623.128415095667, 5634.774609015675, 23461.41346466335, 23650.172243971378, 23732.64401033986, 23811.404714882094, 23817.880910595508, 2386.176769014687, 2489.9915534208676, 2497.8501289922665, 2503.9374015649573, 2554.4124851284123, 14339.569812905629, 14585.781213281809, 14654.0, 14663.0, 14809.851901481063, 1978.3372570189003, 2000.4078404846373, 2017.9293724719564, 2022.6055919095782, 2047.754269487656, 75521.24478755197, 77287.61356193239, 77724.0, 78015.0, 78194.0, 10419.635312764018, 10713.732156696145, 11011.449427528592, 11031.83452248201, 11109.222354435273, 15535.611609709622, 15602.843971560365, 15626.0, 15626.296816643278, 15636.296366663528, 8181.631826567819, 8263.132371100948, 8297.377696672653, 8411.579421028924, 8429.283510901641, 7949.523028618301, 8170.918290817134, 8414.863993360817, 8416.537090460084, 8472.364572656952, 6830.112085428863, 6831.726730930806, 6867.175938887365, 6937.653117344113, 6948.895766563505, 1667.132532044881, 1840.3282801777523, 1955.5893262415052, 1985.662437385648, 1990.532224927141, 1022.6471867205802, 1076.924615276923, 1094.441834664311, 1134.8014097533064, 1179.4397661111075, 18836.81163188378, 19351.225950961805, 19624.215031398584, 19687.40937771869, 19793.604127917362, 1059.6926891201465, 1060.7719340341864, 1083.1767856429012, 1090.111559079348, 1093.5516438260274, 10973.451327433597, 11457.541698331974, 11508.539658413738, 11556.306621602731, 11633.825492617618, 405567.0, 405896.0, 410615.0, 461107.0, 525438.0, 101289.49355253177, 104300.47849760702, 104709.0, 107160.46419768008, 108291.0, 1045.1951996962296, 1066.328213225663, 1103.542030057533, 1104.5636973395465, 1110.4447776112106, 17488.125593720517, 17490.91254543719, 17634.82365176332, 17752.28990840352, 17969.64060718752, 42625.5737442634, 42789.35815962701, 44282.77858610686, 44483.77758111318, 44703.88240294019, 61760.69119654373, 61871.0, 62441.687791560755, 62442.375576243656, 62894.37105628885, 8400.369972251983, 8587.613557389808, 8631.0, 8672.132786721277, 8737.91262087371, 12452.501899924264, 13295.335233238106, 13306.201627902357, 13442.395092220679, 13697.657558561115, 11722.120840937134, 11766.235194712317, 11770.234934729215, 11793.233439826385, 11805.527778888749, 11434.48544815502, 11883.108766842348, 11899.167058305835, 11936.10479214045, 11997.220180688228, 11981.64055078349, 12300.81548776787, 12457.0, 12525.56160534377, 12591.24452532851, 7821.100573434128, 8029.076656184614, 8922.55387230649, 9005.279577633904, 9037.68368107113, 12112.636620901387, 12169.39153042362, 12274.693132671397, 12275.0, 12314.13801033919], "value": 9655.782436969888, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.4795977528538, 1558.1819544739008, 1576.101622075417, 1753.701870681984, 1858.1731129647312, 8409.621567029482, 8827.558622068895, 8970.596323165457, 8997.505137217451, 9295.302852286071, 9107.77230569236, 9113.863292050624, 9167.90832091679, 9170.81658366834, 9183.49490778007, 2294.047970092414, 2322.396176993977, 2322.988385058081, 2325.0699720112007, 2325.3953971967326, 2348.3072493614427, 2386.4988352446007, 2421.1889017179265, 2423.1761201191593, 2426.4783071639595, 2222.533268013719, 2225.5771403433296, 2231.5871563760757, 2269.943251418711, 2280.1905323610126, 2193.8025577698086, 2222.4554984029014, 2224.4883676754275, 2224.810891074261, 2231.944201394962, 2228.465168359582, 2231.866088034707, 2232.5646498932715, 2234.8659080455063, 2236.4632488202715, 9005.594748236344, 9055.954720226422, 9061.13919177677, 9069.455832650063, 9215.262778977665, 5360.544353729938, 5589.161625756169, 5609.859753506154, 5614.354349249807, 5621.100623900156, 3413.4538473844314, 3438.346714124308, 3477.4088404971217, 3503.4919936609303, 3559.501669766235, 1015.9085682288555, 1037.99833161, 1043.964269674131, 1048.4233671480656, 1049.648367796789, 12778.23330600167, 12956.222626642431, 12971.610851674559, 12974.351282435935, 12986.0, 4459.152760975404, 4496.550344965477, 4506.864794056183, 4546.568076032838, 4560.247559152703, 10068.798624027479, 10079.445630490394, 10090.747731306776, 10108.039736225046, 10111.140553053074, 5243.108671525773, 5249.396319423316, 5252.0, 5262.71055091966, 5262.736863156903, 4264.744115353089, 4430.113977204572, 4564.7717614119165, 4802.303665968415, 4900.142475066849, 3928.862489812843, 3959.8020098994934, 3997.220541994341, 4015.5382131055303, 4019.33680942647, 1142.3089031136044, 1156.9942150289194, 1158.611865025226, 1167.2937872587133, 1168.7779321928806, 927.9953600231955, 947.2043483473915, 996.1234113979651, 1030.7989941961396, 1044.3316277582353, 6312.8421789454715, 6351.237851457855, 6403.391677790602, 6574.934250657528, 6723.932760672428, 349.704499697755, 353.2634457156857, 356.2056613751349, 359.1756917873487, 363.50381728939806, 4380.912381752347, 4384.846530371486, 4389.714668546534, 4424.867253982386, 4450.488193857748, 11648.650540483799, 12102.455389507493, 12123.393830308625, 12164.513419463123, 12169.20900141488, 11940.641780746591, 11957.34234617087, 12002.63992080239, 12022.879771202175, 12031.157818952765, 8442.53566053873, 8750.956245218733, 8771.561421929006, 8816.471011739317, 8892.110788921185, 6337.746490140343, 6592.307807680125, 6635.734570617315, 6647.235567909753, 6656.534042617164, 10306.072453479223, 10336.586536538454, 10385.324953877975, 10456.529456174643, 10521.579136834442, 10658.14734821228, 10808.297460665031, 10896.945515272373, 10906.61826836057, 10915.454227288445, 7586.165521792488, 8090.838183236185, 8379.203975622306, 8408.159184081544, 8607.18231767981, 9327.207187389147, 9386.155246027618, 9412.811743765224, 9433.198178154933, 9449.574769135268, 8089.514629122272, 8291.917080829115, 8421.0, 8472.19514146155, 8568.057513673366, 8288.046874609498, 8315.838697802086, 8320.209580089882, 8326.70856520019, 8396.076431592643, 7792.0, 7849.254320839511, 7877.921220787948, 7885.763427097197, 7889.5266284020845, 5626.465485778845, 5696.857578560569, 5771.134329850556, 5808.419158084158, 5837.883242335214, 5262.400293844692, 5271.341082364705, 5332.786688532416, 5444.0, 5445.700486473123], "value": 4665.656766343118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.69838276887367, 337.8513454080205, 340.76146697311873, 340.787008119925, 342.5461263825431, 5177.275181474596, 5354.22363757255, 5373.005993891123, 5413.26920865683, 5495.368032676233, 5330.546903513196, 5366.973165134169, 5375.247465354853, 5392.352917649878, 5394.595405344605, 3961.148353104082, 4132.8966775830695, 4150.3566947123245, 4177.874663760077, 4182.435371224884, 3996.5421265921395, 4141.233871733723, 4160.292750232453, 4162.084341444877, 4168.249335279175, 4084.5915408459214, 4108.28105081611, 4111.362738775495, 4124.1957818225455, 4124.958750412503, 3983.24318379507, 4023.4970628671417, 4133.400656904747, 4141.7514949103, 4159.896002599929, 3551.5915669697797, 3583.3549961007006, 3603.5675718913635, 3608.639136086397, 3625.9456108158397, 5093.516115968977, 5133.6919784813035, 5158.871028224287, 5159.22611608258, 5160.56135228506, 4457.375967364572, 4646.83736069236, 4646.86059418218, 4672.462666793328, 4693.929591056137, 5527.977324195037, 5528.336599608033, 5546.334439867202, 5552.194931734876, 5555.416681248451, 4011.97994010031, 4012.4583181270445, 4023.3361495353233, 4026.1947610478023, 4041.0602910603075, 5688.744006519675, 5809.182546464961, 5816.243888294537, 5834.737436815353, 5835.649861008353, 4098.467199264077, 4100.507939047334, 4105.67154627635, 4121.093359460914, 4123.577365808791, 4145.7719825409895, 4184.0, 4189.350650649154, 4192.601702838226, 4192.664586833046, 4721.6694831362165, 4754.95245047552, 4797.880052998703, 4801.399825021872, 4817.614590832725, 5302.416734159239, 5529.419410961791, 5591.245181900473, 5609.859753506195, 5620.522255608237, 1405.5221224783622, 1414.6984774007938, 1416.4759039155579, 1431.3415828718905, 1437.3819257719072, 893.9061398553189, 912.717057712098, 915.2769312243254, 926.8934072581604, 934.3366209990846, 5270.130428479258, 5336.306280183551, 5430.5384042356845, 5436.0, 5437.8368648940595, 5738.8278351649515, 5765.509931655856, 5765.8558536036935, 5778.451047150514, 5840.0, 5281.392639846466, 5463.262459567987, 5476.479734425223, 5478.369987451414, 5527.336719593674, 3022.3955208957855, 3054.022712731949, 3055.816651000947, 3061.525463553153, 3087.598612180447, 5931.6441013539325, 5942.168096466498, 5996.580239383201, 6010.579259451796, 6014.609050411709, 5656.32124145097, 5768.163616275617, 5803.6807975560905, 5828.941710582924, 5832.941670583325, 5921.437463440966, 5926.259217597801, 5947.524198064145, 5952.958232309328, 5970.910436343372, 5492.588055895744, 5738.770449182153, 5744.3394009687745, 5748.367679555329, 5752.424757524214, 5663.461971112738, 5672.092465205547, 5686.203931449518, 5701.885962280814, 5705.4579814917515, 5915.467607915309, 5950.256217972754, 5959.28488581373, 5980.521558275241, 5981.641101533922, 4016.638502534786, 4141.399497072968, 4144.772037537964, 4194.4966604006495, 4199.950012496875, 5666.46168613981, 5672.2058911751565, 5677.318721753416, 5702.258706368146, 5717.714114294352, 5399.325084364455, 5578.525825304894, 5589.636673616202, 5594.244776955222, 5603.131514615241, 5507.697076660822, 5509.641873278224, 5512.228288039598, 5541.418151094238, 5567.721613919207, 5280.419153893145, 5367.838964831061, 5381.542568881689, 5396.865078372915, 5442.965836491054, 5437.184422336523, 5564.0, 5569.526590239875, 5575.163725441216, 5586.217929489793, 5441.238226648352, 5449.591280654046, 5477.260569823182, 5511.476409741068, 5533.501984821385], "value": 4256.381312635471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.1361400108883, 819.9754007379777, 882.8322618702446, 888.5779254853941, 895.9819417562236, 16017.359305627775, 17066.146692665377, 17101.82898171018, 17152.313907443702, 17155.571110722223, 14947.92526037371, 15003.099814011168, 15016.474423395184, 15072.849271507283, 15072.924635376836, 849.6558893648082, 857.995710021449, 859.4241857955185, 859.7248880358256, 860.8235311761106, 864.7838040489878, 866.9176428239307, 867.9869801952975, 868.0, 868.1665601022994, 741.2772546766886, 754.6792613139421, 758.8102974256436, 759.1194214710937, 877.0834477970536, 892.3441270666056, 892.5983307511646, 893.678275820704, 896.0994200828159, 896.7668406214431, 654.8886689262789, 668.4552090046597, 668.6656671664168, 668.8093893240451, 668.976585819494, 15279.00686455388, 15340.92329538345, 15347.846521534753, 15359.385624575003, 15374.692506149815, 14816.851831481656, 14928.776068358984, 14968.47610333644, 15123.621909452242, 15284.770728439082, 12151.574694885725, 12250.938745306306, 12363.443645035995, 13152.145110567879, 13996.370163342572, 4848.538986819136, 4851.668880563974, 4936.062148191868, 4939.753012349368, 4968.503149685039, 72972.0, 73391.26608733898, 74909.0, 75139.0, 76164.23835761627, 12097.879021209676, 12129.090318226168, 12130.0, 12258.26450412978, 12446.81329780036, 20078.09648565818, 21168.364949051553, 21225.469363266042, 21250.574988500142, 21277.25529606487, 9288.396254243453, 9338.206252468617, 9511.66709165176, 9545.379550329206, 9595.0, 4969.602431805519, 5059.342285502935, 5063.063333283354, 5127.8718032048755, 5157.561607263349, 3741.3639681254253, 3947.2697550953167, 3990.5610382857863, 4038.5557588665224, 4038.8990275243355, 695.3151146121066, 712.7184762018974, 714.4284572342106, 721.7004942948618, 723.562244841863, 766.3639179481017, 794.0986979777878, 817.9263866252065, 825.5995842016617, 838.1953324808252, 11493.138014648935, 11564.42177891102, 11582.69893080468, 11853.585124520772, 12093.87906120945, 752.3830459023658, 790.7311514085123, 807.6163822184533, 814.3688641303038, 821.6713314674179, 5207.0366982108435, 5363.383210930715, 5420.457954204548, 5449.809256675997, 5559.471850174227, 76791.61604191943, 77416.0, 106354.0, 126559.0, 126832.0, 100797.0, 105625.0, 108352.0, 108672.4566377163, 110132.0, 95560.0, 95675.0, 96166.0, 96860.0, 97986.51006744921, 9990.800183996134, 10338.638147664795, 10351.534180962026, 10394.116500097576, 10437.21720870953, 54002.0, 54742.72628636831, 54912.725436372566, 55110.0, 56979.43020569741, 72726.63636681995, 82388.17611824044, 83445.58277208575, 84080.0, 84917.57541212255, 12814.679633008876, 12923.741525169256, 13433.261170635318, 13447.394867230802, 13688.110272832235, 14934.645395357824, 14984.400623975354, 15080.924595376953, 15453.227338633249, 15601.29794159243, 13838.4464621414, 14043.648908777363, 14321.64195895111, 14724.484643037871, 15212.46756363522, 13653.86346136526, 14001.43994240219, 14271.928640356731, 14385.712285754016, 14791.038582492103, 13988.440462381797, 14195.929020355245, 14237.288135592971, 14273.857261427669, 14348.569542913729, 11539.134564907497, 11996.880031199576, 12038.638840834788, 12188.817167742314, 12223.205491643015, 11836.79167206756, 11975.520979160736, 12042.277463352228, 12097.0, 12148.635540933787], "value": 8087.96727566574, "unit": "score"}], "name": "dromaeo_css", "value": 7553.681612140881, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 110.0, 109.0, 108.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 125.0, 191.0, 124.0, 124.0, 146.0, 126.0, 153.0, 124.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 161.0, 161.0, 162.0, 161.0, 163.0, 161.0, 161.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 107.0, 76.0, 76.0, 76.0, 76.0, 104.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 116.0, 85.0, 86.0, 85.0, 114.0, 84.0, 84.0, 130.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 52.0, 51.0, 52.0, 50.0, 53.0, 51.0, 50.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 60.0, 63.0, 62.0, 61.0, 62.0, 61.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 77.0, 77.0, 82.0, 80.0, 78.0, 77.0, 110.0, 77.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 120.0, 116.0, 125.0, 127.0, 119.0, 128.0, 124.0, 123.0], "value": 122.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 157.0, 163.0, 158.0, 154.0, 154.0, 153.0, 152.0, 153.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 60.0, 59.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1477.3, "alertThreshold": 2.0}]} 03:37:49 INFO - Return code: 0 03:37:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:37:49 ERROR - # TBPL SUCCESS # 03:37:49 INFO - Running post-action listener: _resource_record_post_action 03:37:49 INFO - ##### 03:37:49 INFO - ##### Finished run-tests step (success) 03:37:49 INFO - ##### 03:37:49 INFO - Running post-run listener: _resource_record_post_run 03:37:49 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 514777088; Read time: 4; Write time: 674152 03:37:49 INFO - TinderboxPrint: CPU usage
13.1% 03:37:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 03:37:49 INFO - TinderboxPrint: I/O write bytes / time
514,777,088 / 674,152 03:37:49 INFO - TinderboxPrint: CPU idle
8,255.6 (86.9%) 03:37:49 INFO - TinderboxPrint: CPU user
1,195.5 (12.6%) 03:37:49 INFO - TinderboxPrint: Swap in / out
0 / 0 03:37:49 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 247586816; Read time: 0; Write time: 441464 03:37:49 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251637760; Read time: 4; Write time: 228028 03:37:50 INFO - Running post-run listener: _upload_blobber_files 03:37:50 INFO - Blob upload gear active. 03:37:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:37:50 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:37:50 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:37:50 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:37:50 INFO - (blobuploader) - INFO - Open directory for files ... 03:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:37:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:54 INFO - (blobuploader) - INFO - Done attempting. 03:37:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:37:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:37:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:55 INFO - (blobuploader) - INFO - Done attempting. 03:37:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:37:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:56 INFO - (blobuploader) - INFO - Done attempting. 03:37:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:37:56 INFO - Return code: 0 03:37:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:37:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:37:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69e94dff164d5497804537abe946c386297ef553079a2b4c212f72357b6e04ab2783d1dc43d8797365431ac62009b59f0cade7582d043b240f5aed9f88444760", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/477cbb4b1ada8a8bce560afbbc7568bbbebac8a49ab291c2fd4296dab3e51441cb7bc25fbf85c01070be06ccbf949d27452360ffe0dc33db2de1c5dee9b338ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb1aea7e80971728535f4addf0379239b93c72197adf4a7c502c1086e9fab0e681c1047fe17d2d70f0e82f5142c4b708fd8404ad36849eebf3e523cd4f7d18b"} 03:37:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:37:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:37:56 INFO - Contents: 03:37:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69e94dff164d5497804537abe946c386297ef553079a2b4c212f72357b6e04ab2783d1dc43d8797365431ac62009b59f0cade7582d043b240f5aed9f88444760", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/477cbb4b1ada8a8bce560afbbc7568bbbebac8a49ab291c2fd4296dab3e51441cb7bc25fbf85c01070be06ccbf949d27452360ffe0dc33db2de1c5dee9b338ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb1aea7e80971728535f4addf0379239b93c72197adf4a7c502c1086e9fab0e681c1047fe17d2d70f0e82f5142c4b708fd8404ad36849eebf3e523cd4f7d18b"} 03:37:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:37:56 INFO - Copying logs to upload dir... 03:37:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:37:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.403699 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-30 03:37:54.482573) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:37:54.485664) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857 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/69e94dff164d5497804537abe946c386297ef553079a2b4c212f72357b6e04ab2783d1dc43d8797365431ac62009b59f0cade7582d043b240f5aed9f88444760", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/477cbb4b1ada8a8bce560afbbc7568bbbebac8a49ab291c2fd4296dab3e51441cb7bc25fbf85c01070be06ccbf949d27452360ffe0dc33db2de1c5dee9b338ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb1aea7e80971728535f4addf0379239b93c72197adf4a7c502c1086e9fab0e681c1047fe17d2d70f0e82f5142c4b708fd8404ad36849eebf3e523cd4f7d18b"} build_url:https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/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/69e94dff164d5497804537abe946c386297ef553079a2b4c212f72357b6e04ab2783d1dc43d8797365431ac62009b59f0cade7582d043b240f5aed9f88444760", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/477cbb4b1ada8a8bce560afbbc7568bbbebac8a49ab291c2fd4296dab3e51441cb7bc25fbf85c01070be06ccbf949d27452360ffe0dc33db2de1c5dee9b338ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb1aea7e80971728535f4addf0379239b93c72197adf4a7c502c1086e9fab0e681c1047fe17d2d70f0e82f5142c4b708fd8404ad36849eebf3e523cd4f7d18b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:37:54.514581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:37:54.514895) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1469873843.115106-1536543857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:37:54.597885) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:37:54.598601) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:37:54.599220) ========= ========= Total master_lag: 0.16 =========