builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1469683799.22 results: success (0) buildid: 20160727194525 builduid: 636c6e16805c4eedbc21278135578887 revision: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.223176) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.223982) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.224320) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.282363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.282724) ========= 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=db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 22:29:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.4M=0s 2016-07-27 22:29:59 (42.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091531 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.441058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.441577) ========= 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=db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017860 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:29:59.480154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 22:29:59.480541) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 73ce55a62039981fd0f7843ab3edd0dfbd8b839f --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 73ce55a62039981fd0f7843ab3edd0dfbd8b839f --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=db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 22:29:59,590 truncating revision to first 12 chars 2016-07-27 22:29:59,591 Setting DEBUG logging. 2016-07-27 22:29:59,591 attempt 1/10 2016-07-27 22:29:59,591 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/73ce55a62039?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 22:30:00,450 unpacking tar archive at: mozilla-aurora-73ce55a62039/testing/mozharness/ program finished with exit code 0 elapsedTime=1.211495 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 22:30:00.792501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.792903) ========= script_repo_revision: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.793425) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.793791) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 22:30:00.948500) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-27 22:30:00.948840) ========= /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=db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:30:01 INFO - MultiFileLogger online at 20160727 22:30:01 in /builds/slave/test 22:30:01 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 22:30:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:30:01 INFO - {'append_to_log': False, 22:30:01 INFO - 'base_work_dir': '/builds/slave/test', 22:30:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:30:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:30:01 INFO - 'branch': 'Mozilla-Aurora', 22:30:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:30:01 INFO - 'config_files': ('talos/linux_config.py',), 22:30:01 INFO - 'default_actions': ('clobber', 22:30:01 INFO - 'read-buildbot-config', 22:30:01 INFO - 'download-and-extract', 22:30:01 INFO - 'populate-webroot', 22:30:01 INFO - 'create-virtualenv', 22:30:01 INFO - 'install', 22:30:01 INFO - 'run-tests'), 22:30:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:30:01 INFO - 'download_minidump_stackwalk': True, 22:30:01 INFO - 'download_symbols': 'ondemand', 22:30:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:30:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:30:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:30:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:30:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:30:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:30:01 INFO - 'installer_path': 'installer.exe', 22:30:01 INFO - 'log_level': 'info', 22:30:01 INFO - 'log_name': 'talos', 22:30:01 INFO - 'log_to_console': True, 22:30:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:30:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:30:01 INFO - 'opt_config_files': (), 22:30:01 INFO - 'pip_index': False, 22:30:01 INFO - 'sps_profile': False, 22:30:01 INFO - 'sps_profile_interval': 0, 22:30:01 INFO - 'suite': 'dromaeojs', 22:30:01 INFO - 'system_bits': '32', 22:30:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:30:01 INFO - 'title': 'talos-linux64-ix-031', 22:30:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:30:01 INFO - 'use_talos_json': True, 22:30:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:30:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:30:01 INFO - 'work_dir': 'build'} 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running clobber step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: clobber 22:30:01 INFO - rmtree: /builds/slave/test/build 22:30:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:30:01 INFO - Running post-action listener: _resource_record_post_action 22:30:01 INFO - ##### 22:30:01 INFO - ##### Finished clobber step (success) 22:30:01 INFO - ##### 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running read-buildbot-config step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: read_buildbot_config 22:30:01 INFO - Using buildbot properties: 22:30:01 INFO - { 22:30:01 INFO - "project": "", 22:30:01 INFO - "product": "firefox", 22:30:01 INFO - "script_repo_revision": "production", 22:30:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:30:01 INFO - "repository": "", 22:30:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:30:01 INFO - "buildid": "20160727194525", 22:30:01 INFO - "pgo_build": "True", 22:30:01 INFO - "basedir": "/builds/slave/test", 22:30:01 INFO - "buildnumber": 637, 22:30:01 INFO - "slavename": "talos-linux64-ix-031", 22:30:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:30:01 INFO - "platform": "ubuntu64_hw", 22:30:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:30:01 INFO - "branch": "mozilla-aurora", 22:30:01 INFO - "repo_path": "releases/mozilla-aurora", 22:30:01 INFO - "revision": "73ce55a62039981fd0f7843ab3edd0dfbd8b839f", 22:30:01 INFO - "stage_platform": "linux64-pgo", 22:30:01 INFO - "builduid": "636c6e16805c4eedbc21278135578887", 22:30:01 INFO - "slavebuilddir": "test" 22:30:01 INFO - } 22:30:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 22:30:01 INFO - Running post-action listener: _resource_record_post_action 22:30:01 INFO - ##### 22:30:01 INFO - ##### Finished read-buildbot-config step (success) 22:30:01 INFO - ##### 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running download-and-extract step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: download_and_extract 22:30:01 INFO - mkdir: /builds/slave/test/build/tests 22:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:01 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:30:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:07 INFO - Downloaded 1361 bytes. 22:30:07 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:30:07 INFO - Using the following test package requirements: 22:30:07 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 22:30:07 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:30:07 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:30:07 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'jsshell-linux-x86_64.zip'], 22:30:07 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:30:07 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 22:30:07 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:30:07 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:30:07 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:30:07 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:30:07 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:30:07 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:07 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:14 INFO - Downloaded 24495303 bytes. 22:30:14 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'] 22:30:14 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 22:30:16 INFO - Return code: 0 22:30:16 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 22:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:16 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:30:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:30:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:17 INFO - Downloaded 24495303 bytes. 22:30:17 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'] 22:30:17 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 22:30:18 INFO - Return code: 0 22:30:18 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')]} 22:30:18 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:30:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:30:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:30:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:19 INFO - Downloaded 10833369 bytes. 22:30:19 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'] 22:30:19 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 22:30:19 INFO - Return code: 0 22:30:19 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')]} 22:30:19 INFO - https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:30:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aywU8DYvRxqd8iA4u1-NPA/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 22:30:20 INFO - Downloaded 63654269 bytes. 22:30:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:20 INFO - mkdir: /builds/slave/test/properties 22:30:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:30:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:30:20 INFO - Contents: 22:30:20 INFO - build_url:https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:30:21 INFO - Running post-action listener: _resource_record_post_action 22:30:21 INFO - Running post-action listener: set_extra_try_arguments 22:30:21 INFO - ##### 22:30:21 INFO - ##### Finished download-and-extract step (success) 22:30:21 INFO - ##### 22:30:21 INFO - ##### 22:30:21 INFO - ##### Running populate-webroot step. 22:30:21 INFO - ##### 22:30:21 INFO - Running pre-action listener: _resource_record_pre_action 22:30:21 INFO - Running main action method: populate_webroot 22:30:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:30:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:30:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:30:21 INFO - '--tppagecycles', 22:30:21 INFO - '7'], 22:30:21 INFO - 'tests': ['tsvgm']}}, 22:30:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:30:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:30:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:30:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:30:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['damp', 'tps']}, 22:30:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'tests': ['damp', 'tps']}, 22:30:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['dromaeo_dom']}, 22:30:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:30:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['a11yr', 22:30:21 INFO - 'ts_paint', 22:30:21 INFO - 'tpaint', 22:30:21 INFO - 'sessionrestore', 22:30:21 INFO - 'sessionrestore_no_auto_restore', 22:30:21 INFO - 'tabpaint']}, 22:30:21 INFO - 'other-e10s': {'tests': ['a11yr', 22:30:21 INFO - 'ts_paint', 22:30:21 INFO - 'tpaint', 22:30:21 INFO - 'sessionrestore', 22:30:21 INFO - 'sessionrestore_no_auto_restore', 22:30:21 INFO - 'tabpaint']}, 22:30:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:30:21 INFO - 'ts_paint', 22:30:21 INFO - 'tpaint', 22:30:21 INFO - 'sessionrestore', 22:30:21 INFO - 'sessionrestore_no_auto_restore', 22:30:21 INFO - 'tabpaint']}, 22:30:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:30:21 INFO - 'ts_paint', 22:30:21 INFO - 'tpaint', 22:30:21 INFO - 'sessionrestore', 22:30:21 INFO - 'sessionrestore_no_auto_restore', 22:30:21 INFO - 'tabpaint']}, 22:30:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['a11yr', 22:30:21 INFO - 'ts_paint', 22:30:21 INFO - 'tpaint', 22:30:21 INFO - 'sessionrestore', 22:30:21 INFO - 'sessionrestore_no_auto_restore', 22:30:21 INFO - 'tabpaint']}, 22:30:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['a11yr', 22:30:21 INFO - 'ts_paint', 22:30:21 INFO - 'tpaint', 22:30:21 INFO - 'sessionrestore', 22:30:21 INFO - 'sessionrestore_no_auto_restore', 22:30:21 INFO - 'tabpaint']}, 22:30:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['tsvgx', 22:30:21 INFO - 'tsvgr_opacity', 22:30:21 INFO - 'tart', 22:30:21 INFO - 'tscrollx', 22:30:21 INFO - 'cart']}, 22:30:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:30:21 INFO - 'tsvgr_opacity', 22:30:21 INFO - 'tart', 22:30:21 INFO - 'tscrollx', 22:30:21 INFO - 'cart']}, 22:30:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'talos_options': ['--disable-e10s'], 22:30:21 INFO - 'tests': ['tp5o']}, 22:30:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'tests': ['tp5o']}, 22:30:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'talos_options': ['--disable-e10s', 22:30:21 INFO - '--xperf_path', 22:30:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:21 INFO - 'tests': ['tp5n']}, 22:30:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:21 INFO - 'talos_options': ['--xperf_path', 22:30:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:21 INFO - 'tests': ['tp5n']}}, 22:30:21 INFO - 'talos.zip': {'path': '', 22:30:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:30:21 INFO - Running post-action listener: _resource_record_post_action 22:30:21 INFO - ##### 22:30:21 INFO - ##### Finished populate-webroot step (success) 22:30:21 INFO - ##### 22:30:21 INFO - ##### 22:30:21 INFO - ##### Running create-virtualenv step. 22:30:21 INFO - ##### 22:30:21 INFO - Running pre-action listener: _resource_record_pre_action 22:30:21 INFO - Running main action method: create_virtualenv 22:30:21 INFO - Creating virtualenv /builds/slave/test/build/venv 22:30:21 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 22:30:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:30:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:30:21 INFO - Using real prefix '/usr' 22:30:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:30:22 INFO - Installing distribute.............................................................................................................................................................................................done. 22:30:24 INFO - Installing pip.................done. 22:30:24 INFO - Return code: 0 22:30:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:30:24 INFO - Using env: {'DISPLAY': ':0', 22:30:24 INFO - 'HOME': '/home/cltbld', 22:30:24 INFO - 'LANG': 'en_US.UTF-8', 22:30:24 INFO - 'LANGUAGE': 'en_US:en', 22:30:24 INFO - 'LOGNAME': 'cltbld', 22:30:24 INFO - 'MAIL': '/var/mail/cltbld', 22:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:24 INFO - 'MOZ_NO_REMOTE': '1', 22:30:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:24 INFO - 'NO_EM_RESTART': '1', 22:30:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:24 INFO - 'PWD': '/builds/slave/test', 22:30:24 INFO - 'SHELL': '/bin/bash', 22:30:24 INFO - 'SHLVL': '1', 22:30:24 INFO - 'TERM': 'linux', 22:30:24 INFO - 'TMOUT': '86400', 22:30:24 INFO - 'USER': 'cltbld', 22:30:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:24 INFO - '_': '/tools/buildbot/bin/python'} 22:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:24 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 22:30:24 INFO - Cleaning up... 22:30:24 INFO - Return code: 0 22:30:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:30:24 INFO - Using env: {'DISPLAY': ':0', 22:30:24 INFO - 'HOME': '/home/cltbld', 22:30:24 INFO - 'LANG': 'en_US.UTF-8', 22:30:24 INFO - 'LANGUAGE': 'en_US:en', 22:30:24 INFO - 'LOGNAME': 'cltbld', 22:30:24 INFO - 'MAIL': '/var/mail/cltbld', 22:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:24 INFO - 'MOZ_NO_REMOTE': '1', 22:30:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:24 INFO - 'NO_EM_RESTART': '1', 22:30:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:24 INFO - 'PWD': '/builds/slave/test', 22:30:24 INFO - 'SHELL': '/bin/bash', 22:30:24 INFO - 'SHLVL': '1', 22:30:24 INFO - 'TERM': 'linux', 22:30:24 INFO - 'TMOUT': '86400', 22:30:24 INFO - 'USER': 'cltbld', 22:30:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:24 INFO - '_': '/tools/buildbot/bin/python'} 22:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:24 INFO - Downloading/unpacking psutil>=3.1.1 22:30:24 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 22:30:24 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 22:30:24 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 22:30:24 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 22:30:24 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 22:30:24 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 22:30:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:30:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:26 INFO - Installing collected packages: psutil 22:30:26 INFO - Running setup.py install for psutil 22:30:26 INFO - building 'psutil._psutil_linux' extension 22:30:26 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 22:30:26 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 22:30:26 INFO - building 'psutil._psutil_posix' extension 22:30:26 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 22:30:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:30:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:27 INFO - Successfully installed psutil 22:30:27 INFO - Cleaning up... 22:30:27 INFO - Return code: 0 22:30:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:30:27 INFO - Using env: {'DISPLAY': ':0', 22:30:27 INFO - 'HOME': '/home/cltbld', 22:30:27 INFO - 'LANG': 'en_US.UTF-8', 22:30:27 INFO - 'LANGUAGE': 'en_US:en', 22:30:27 INFO - 'LOGNAME': 'cltbld', 22:30:27 INFO - 'MAIL': '/var/mail/cltbld', 22:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:27 INFO - 'MOZ_NO_REMOTE': '1', 22:30:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:27 INFO - 'NO_EM_RESTART': '1', 22:30:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:27 INFO - 'PWD': '/builds/slave/test', 22:30:27 INFO - 'SHELL': '/bin/bash', 22:30:27 INFO - 'SHLVL': '1', 22:30:27 INFO - 'TERM': 'linux', 22:30:27 INFO - 'TMOUT': '86400', 22:30:27 INFO - 'USER': 'cltbld', 22:30:27 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:27 INFO - '_': '/tools/buildbot/bin/python'} 22:30:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:30:27 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 22:30:27 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 22:30:27 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 22:30:27 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 22:30:27 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 22:30:27 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 22:30:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:30:29 INFO - Installing collected packages: mozsystemmonitor 22:30:29 INFO - Running setup.py install for mozsystemmonitor 22:30:29 INFO - Successfully installed mozsystemmonitor 22:30:29 INFO - Cleaning up... 22:30:29 INFO - Return code: 0 22:30:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:30:29 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')]} 22:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:29 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')]} 22:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:29 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 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:29 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 22:30:29 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 22:30:29 INFO - Using env: {'DISPLAY': ':0', 22:30:29 INFO - 'HOME': '/home/cltbld', 22:30:29 INFO - 'LANG': 'en_US.UTF-8', 22:30:29 INFO - 'LANGUAGE': 'en_US:en', 22:30:29 INFO - 'LOGNAME': 'cltbld', 22:30:29 INFO - 'MAIL': '/var/mail/cltbld', 22:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:29 INFO - 'MOZ_NO_REMOTE': '1', 22:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:29 INFO - 'NO_EM_RESTART': '1', 22:30:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:29 INFO - 'PWD': '/builds/slave/test', 22:30:29 INFO - 'SHELL': '/bin/bash', 22:30:29 INFO - 'SHLVL': '1', 22:30:29 INFO - 'TERM': 'linux', 22:30:29 INFO - 'TMOUT': '86400', 22:30:29 INFO - 'USER': 'cltbld', 22:30:29 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:29 INFO - '_': '/tools/buildbot/bin/python'} 22:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:29 INFO - Downloading/unpacking jsonschema==2.5.1 22:30:29 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 22:30:29 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 22:30:29 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 22:30:29 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 22:30:29 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 22:30:29 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 22:30:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:30:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:30:31 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 22:30:31 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 22:30:31 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 22:30:31 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 22:30:31 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 22:30:31 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 22:30:31 INFO - Downloading functools32-3.2.3-2.tar.gz 22:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:30:31 INFO - warning: no files found matching '*.txt' 22:30:31 INFO - no previously-included directories found matching 'build' 22:30:31 INFO - no previously-included directories found matching 'dist' 22:30:31 INFO - no previously-included directories found matching '.git*' 22:30:31 INFO - Installing collected packages: jsonschema, functools32 22:30:31 INFO - Running setup.py install for functools32 22:30:32 INFO - warning: no files found matching '*.txt' 22:30:32 INFO - no previously-included directories found matching 'build' 22:30:32 INFO - no previously-included directories found matching 'dist' 22:30:32 INFO - no previously-included directories found matching '.git*' 22:30:32 INFO - Successfully installed jsonschema functools32 22:30:32 INFO - Cleaning up... 22:30:32 INFO - Return code: 0 22:30:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:30:32 INFO - Using env: {'DISPLAY': ':0', 22:30:32 INFO - 'HOME': '/home/cltbld', 22:30:32 INFO - 'LANG': 'en_US.UTF-8', 22:30:32 INFO - 'LANGUAGE': 'en_US:en', 22:30:32 INFO - 'LOGNAME': 'cltbld', 22:30:32 INFO - 'MAIL': '/var/mail/cltbld', 22:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:32 INFO - 'MOZ_NO_REMOTE': '1', 22:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:32 INFO - 'NO_EM_RESTART': '1', 22:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:32 INFO - 'PWD': '/builds/slave/test', 22:30:32 INFO - 'SHELL': '/bin/bash', 22:30:32 INFO - 'SHLVL': '1', 22:30:32 INFO - 'TERM': 'linux', 22:30:32 INFO - 'TMOUT': '86400', 22:30:32 INFO - 'USER': 'cltbld', 22:30:32 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:32 INFO - '_': '/tools/buildbot/bin/python'} 22:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:32 INFO - Downloading/unpacking blobuploader==1.2.4 22:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:34 INFO - Downloading blobuploader-1.2.4.tar.gz 22:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:30:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:30:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:34 INFO - Downloading docopt-0.6.1.tar.gz 22:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:30:34 INFO - Installing collected packages: blobuploader, requests, docopt 22:30:34 INFO - Running setup.py install for blobuploader 22:30:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:30:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:30:35 INFO - Running setup.py install for requests 22:30:35 INFO - Running setup.py install for docopt 22:30:35 INFO - Successfully installed blobuploader requests docopt 22:30:35 INFO - Cleaning up... 22:30:35 INFO - Return code: 0 22:30:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:35 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 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:35 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 22:30:35 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 22:30:35 INFO - Using env: {'DISPLAY': ':0', 22:30:35 INFO - 'HOME': '/home/cltbld', 22:30:35 INFO - 'LANG': 'en_US.UTF-8', 22:30:35 INFO - 'LANGUAGE': 'en_US:en', 22:30:35 INFO - 'LOGNAME': 'cltbld', 22:30:35 INFO - 'MAIL': '/var/mail/cltbld', 22:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:35 INFO - 'MOZ_NO_REMOTE': '1', 22:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:35 INFO - 'NO_EM_RESTART': '1', 22:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:35 INFO - 'PWD': '/builds/slave/test', 22:30:35 INFO - 'SHELL': '/bin/bash', 22:30:35 INFO - 'SHLVL': '1', 22:30:35 INFO - 'TERM': 'linux', 22:30:35 INFO - 'TMOUT': '86400', 22:30:35 INFO - 'USER': 'cltbld', 22:30:35 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:35 INFO - '_': '/tools/buildbot/bin/python'} 22:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:35 INFO - Running setup.py (path:/tmp/pip-lWSyRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:35 INFO - Running setup.py (path:/tmp/pip-p6DPf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:35 INFO - Running setup.py (path:/tmp/pip-iywHBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:36 INFO - Running setup.py (path:/tmp/pip-Hora6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:36 INFO - Running setup.py (path:/tmp/pip-khhO4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:36 INFO - Running setup.py (path:/tmp/pip-HEkEmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:36 INFO - Running setup.py (path:/tmp/pip-9o2msZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:36 INFO - Running setup.py (path:/tmp/pip-4iZ_tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:36 INFO - Running setup.py (path:/tmp/pip-87WrsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:36 INFO - Running setup.py (path:/tmp/pip-8HJmj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:36 INFO - Running setup.py (path:/tmp/pip-u5hvUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:36 INFO - Running setup.py (path:/tmp/pip-PQflsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:36 INFO - Running setup.py (path:/tmp/pip-gPMziA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:36 INFO - Running setup.py (path:/tmp/pip-vOPjmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:36 INFO - Running setup.py (path:/tmp/pip-3ELNOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:36 INFO - Running setup.py (path:/tmp/pip-NDaxda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:36 INFO - Running setup.py (path:/tmp/pip-tt7Z7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:30:37 INFO - Running setup.py install for manifestparser 22:30:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Running setup.py install for mozcrash 22:30:37 INFO - Running setup.py install for mozdebug 22:30:37 INFO - Running setup.py install for mozdevice 22:30:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Running setup.py install for mozfile 22:30:37 INFO - Running setup.py install for mozhttpd 22:30:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Running setup.py install for mozinfo 22:30:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Running setup.py install for mozInstall 22:30:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:30:37 INFO - Running setup.py install for mozleak 22:30:37 INFO - Running setup.py install for mozlog 22:30:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Running setup.py install for moznetwork 22:30:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Running setup.py install for mozprocess 22:30:38 INFO - Running setup.py install for mozprofile 22:30:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Running setup.py install for mozrunner 22:30:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Running setup.py install for mozscreenshot 22:30:38 INFO - Running setup.py install for moztest 22:30:38 INFO - Running setup.py install for mozversion 22:30:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:30:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:30:38 INFO - Cleaning up... 22:30:38 INFO - Return code: 0 22:30:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:38 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 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:38 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 22:30:38 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 22:30:38 INFO - Using env: {'DISPLAY': ':0', 22:30:38 INFO - 'HOME': '/home/cltbld', 22:30:38 INFO - 'LANG': 'en_US.UTF-8', 22:30:38 INFO - 'LANGUAGE': 'en_US:en', 22:30:38 INFO - 'LOGNAME': 'cltbld', 22:30:38 INFO - 'MAIL': '/var/mail/cltbld', 22:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:38 INFO - 'MOZ_NO_REMOTE': '1', 22:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:38 INFO - 'NO_EM_RESTART': '1', 22:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:38 INFO - 'PWD': '/builds/slave/test', 22:30:38 INFO - 'SHELL': '/bin/bash', 22:30:38 INFO - 'SHLVL': '1', 22:30:38 INFO - 'TERM': 'linux', 22:30:38 INFO - 'TMOUT': '86400', 22:30:38 INFO - 'USER': 'cltbld', 22:30:38 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:38 INFO - '_': '/tools/buildbot/bin/python'} 22:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:39 INFO - Running setup.py (path:/tmp/pip-IilVcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:39 INFO - Running setup.py (path:/tmp/pip-UQvRen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:39 INFO - Running setup.py (path:/tmp/pip-8CMCbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:39 INFO - Running setup.py (path:/tmp/pip-cFy92Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:39 INFO - Running setup.py (path:/tmp/pip-yMkdm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:39 INFO - Running setup.py (path:/tmp/pip-S4P9Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:39 INFO - Running setup.py (path:/tmp/pip-BzISRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:39 INFO - Running setup.py (path:/tmp/pip-LrcPgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:39 INFO - Running setup.py (path:/tmp/pip-J27vz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:39 INFO - Running setup.py (path:/tmp/pip-mYw_76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:39 INFO - Running setup.py (path:/tmp/pip-TNgA9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:39 INFO - Running setup.py (path:/tmp/pip-rwSSOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:39 INFO - Running setup.py (path:/tmp/pip-ZSbhSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:39 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)) 22:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:39 INFO - Running setup.py (path:/tmp/pip-YxRstN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:40 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)) 22:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:40 INFO - Running setup.py (path:/tmp/pip-9GBpLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:40 INFO - Running setup.py (path:/tmp/pip-KuXcpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:40 INFO - Running setup.py (path:/tmp/pip-t4bL7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:30:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30:42 INFO - Downloading blessings-1.6.tar.gz 22:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:30:42 INFO - Installing collected packages: blessings 22:30:42 INFO - Running setup.py install for blessings 22:30:42 INFO - Successfully installed blessings 22:30:42 INFO - Cleaning up... 22:30:42 INFO - Return code: 0 22:30:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:30:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:42 INFO - Reading from file tmpfile_stdout 22:30:42 INFO - Current package versions: 22:30:42 INFO - argparse == 1.2.1 22:30:42 INFO - blessings == 1.6 22:30:42 INFO - blobuploader == 1.2.4 22:30:42 INFO - docopt == 0.6.1 22:30:42 INFO - functools32 == 3.2.3-2 22:30:42 INFO - jsonschema == 2.5.1 22:30:42 INFO - manifestparser == 1.1 22:30:42 INFO - mozInstall == 1.12 22:30:42 INFO - mozcrash == 0.17 22:30:42 INFO - mozdebug == 0.1 22:30:42 INFO - mozdevice == 0.48 22:30:42 INFO - mozfile == 1.2 22:30:42 INFO - mozhttpd == 0.7 22:30:42 INFO - mozinfo == 0.9 22:30:42 INFO - mozleak == 0.1 22:30:42 INFO - mozlog == 3.2 22:30:42 INFO - moznetwork == 0.27 22:30:42 INFO - mozprocess == 0.23 22:30:42 INFO - mozprofile == 0.28 22:30:42 INFO - mozrunner == 6.12 22:30:42 INFO - mozscreenshot == 0.1 22:30:42 INFO - mozsystemmonitor == 0.3 22:30:42 INFO - moztest == 0.7 22:30:42 INFO - mozversion == 1.4 22:30:42 INFO - psutil == 3.1.1 22:30:42 INFO - requests == 1.2.3 22:30:42 INFO - wsgiref == 0.1.2 22:30:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:42 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 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:42 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 22:30:42 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 22:30:42 INFO - Using env: {'DISPLAY': ':0', 22:30:42 INFO - 'HOME': '/home/cltbld', 22:30:42 INFO - 'LANG': 'en_US.UTF-8', 22:30:42 INFO - 'LANGUAGE': 'en_US:en', 22:30:42 INFO - 'LOGNAME': 'cltbld', 22:30:42 INFO - 'MAIL': '/var/mail/cltbld', 22:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:42 INFO - 'MOZ_NO_REMOTE': '1', 22:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:42 INFO - 'NO_EM_RESTART': '1', 22:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:42 INFO - 'PWD': '/builds/slave/test', 22:30:42 INFO - 'SHELL': '/bin/bash', 22:30:42 INFO - 'SHLVL': '1', 22:30:42 INFO - 'TERM': 'linux', 22:30:42 INFO - 'TMOUT': '86400', 22:30:42 INFO - 'USER': 'cltbld', 22:30:42 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:42 INFO - '_': '/tools/buildbot/bin/python'} 22:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 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)) 22:30:42 INFO - Cleaning up... 22:30:42 INFO - Return code: 0 22:30:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30: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'] in /builds/slave/test/build 22:30: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 22:30:42 INFO - Using env: {'DISPLAY': ':0', 22:30:42 INFO - 'HOME': '/home/cltbld', 22:30:42 INFO - 'LANG': 'en_US.UTF-8', 22:30:42 INFO - 'LANGUAGE': 'en_US:en', 22:30:42 INFO - 'LOGNAME': 'cltbld', 22:30:42 INFO - 'MAIL': '/var/mail/cltbld', 22:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:42 INFO - 'MOZ_NO_REMOTE': '1', 22:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:42 INFO - 'NO_EM_RESTART': '1', 22:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:42 INFO - 'PWD': '/builds/slave/test', 22:30:42 INFO - 'SHELL': '/bin/bash', 22:30:42 INFO - 'SHLVL': '1', 22:30:42 INFO - 'TERM': 'linux', 22:30:42 INFO - 'TMOUT': '86400', 22:30:42 INFO - 'USER': 'cltbld', 22:30:42 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:42 INFO - '_': '/tools/buildbot/bin/python'} 22:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:30:43 INFO - Cleaning up... 22:30:43 INFO - Return code: 0 22:30:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6102cd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2451350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, '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': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', '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 22:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:30:43 INFO - Using env: {'DISPLAY': ':0', 22:30:43 INFO - 'HOME': '/home/cltbld', 22:30:43 INFO - 'LANG': 'en_US.UTF-8', 22:30:43 INFO - 'LANGUAGE': 'en_US:en', 22:30:43 INFO - 'LOGNAME': 'cltbld', 22:30:43 INFO - 'MAIL': '/var/mail/cltbld', 22:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:43 INFO - 'MOZ_NO_REMOTE': '1', 22:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:43 INFO - 'NO_EM_RESTART': '1', 22:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:43 INFO - 'PWD': '/builds/slave/test', 22:30:43 INFO - 'SHELL': '/bin/bash', 22:30:43 INFO - 'SHLVL': '1', 22:30:43 INFO - 'TERM': 'linux', 22:30:43 INFO - 'TMOUT': '86400', 22:30:43 INFO - 'USER': 'cltbld', 22:30:43 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:43 INFO - '_': '/tools/buildbot/bin/python'} 22:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:43 INFO - Downloading/unpacking flake8 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:30:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:30:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:30:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:30:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:30:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:30:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:30:45 INFO - no previously-included directories found matching 'docs/_build' 22:30:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:30:45 INFO - Running setup.py install for pep8 22:30:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:30:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:30:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:30:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:30:46 INFO - no previously-included directories found matching 'docs/_build' 22:30:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:30:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:30:46 INFO - Cleaning up... 22:30:46 INFO - Return code: 0 22:30:46 INFO - Running post-action listener: _resource_record_post_action 22:30:46 INFO - Running post-action listener: _start_resource_monitoring 22:30:46 INFO - Starting resource monitoring. 22:30:46 INFO - ##### 22:30:46 INFO - ##### Finished create-virtualenv step (success) 22:30:46 INFO - ##### 22:30:46 INFO - ##### 22:30:46 INFO - ##### Running install step. 22:30:46 INFO - ##### 22:30:46 INFO - Running pre-action listener: _resource_record_pre_action 22:30:46 INFO - Running main action method: install 22:30:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:46 INFO - Reading from file tmpfile_stdout 22:30:46 INFO - Detecting whether we're running mozinstall >=1.0... 22:30:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:30:46 INFO - Reading from file tmpfile_stdout 22:30:46 INFO - Output received: 22:30:46 INFO - Usage: mozinstall [options] installer 22:30:46 INFO - Options: 22:30:46 INFO - -h, --help show this help message and exit 22:30:46 INFO - -d DEST, --destination=DEST 22:30:46 INFO - Directory to install application into. [default: 22:30:46 INFO - "/builds/slave/test"] 22:30:46 INFO - --app=APP Application being installed. [default: firefox] 22:30:46 INFO - mkdir: /builds/slave/test/build/application 22:30:46 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'] 22:30:46 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 22:31:02 INFO - Reading from file tmpfile_stdout 22:31:02 INFO - Output received: 22:31:02 INFO - /builds/slave/test/build/application/firefox/firefox 22:31:02 INFO - Running post-action listener: _resource_record_post_action 22:31:02 INFO - ##### 22:31:02 INFO - ##### Finished install step (success) 22:31:02 INFO - ##### 22:31:02 INFO - ##### 22:31:02 INFO - ##### Running run-tests step. 22:31:02 INFO - ##### 22:31:02 INFO - Running pre-action listener: _resource_record_pre_action 22:31:02 INFO - Running main action method: run_tests 22:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:31:02 INFO - Python 2.7.3 22:31:02 INFO - Return code: 0 22:31:02 INFO - grabbing minidump binary from tooltool 22:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:31:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e01c0>, '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 0x24e10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:31:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:31:02 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 22:31:02 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 22:31:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:31:02 INFO - Return code: 0 22:31:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:31:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:31:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:31:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:31:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:31:03 INFO - Return code: 0 22:31:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 22:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 22:31:03 INFO - Using env: {'DISPLAY': ':0', 22:31:03 INFO - 'HOME': '/home/cltbld', 22:31:03 INFO - 'LANG': 'en_US.UTF-8', 22:31:03 INFO - 'LANGUAGE': 'en_US:en', 22:31:03 INFO - 'LOGNAME': 'cltbld', 22:31:03 INFO - 'MAIL': '/var/mail/cltbld', 22:31:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:31:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:03 INFO - 'MOZ_NO_REMOTE': '1', 22:31:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:31:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:03 INFO - 'NO_EM_RESTART': '1', 22:31:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:03 INFO - 'PWD': '/builds/slave/test', 22:31:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:31:03 INFO - 'SHELL': '/bin/bash', 22:31:03 INFO - 'SHLVL': '1', 22:31:03 INFO - 'TERM': 'linux', 22:31:03 INFO - 'TMOUT': '86400', 22:31:03 INFO - 'USER': 'cltbld', 22:31:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237', 22:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:03 INFO - '_': '/tools/buildbot/bin/python'} 22:31:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 22:31:03 INFO - mozversion application_buildid: 20160727194525 22:31:03 INFO - mozversion application_changeset: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f 22:31:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:31:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:31:03 INFO - mozversion application_name: Firefox 22:31:03 INFO - mozversion application_remotingname: firefox-dev 22:31:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:31:03 INFO - mozversion application_vendor: Mozilla 22:31:03 INFO - mozversion application_version: 49.0a2 22:31:03 INFO - mozversion platform_buildid: 20160727194525 22:31:03 INFO - mozversion platform_changeset: 73ce55a62039981fd0f7843ab3edd0dfbd8b839f 22:31:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:31:03 INFO - mozversion platform_version: 49.0a2 22:31:03 INFO - using testdate: 1469683863 22:31:03 INFO - actual date: 1469683863 22:31:03 INFO - starting webserver on 'localhost:54021' 22:31:03 INFO - SUITE-START | Running 2 tests 22:31:03 INFO - TEST-START | dromaeo_css 22:31:03 INFO - operating with platform_type : linux_ 22:31:03 INFO - Initialising browser for dromaeo_css test... 22:31:03 INFO - TEST-INFO | started process 25997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRoAutq/profile http://localhost:54021/getInfo.html) 22:31:07 INFO - PROCESS | 25997 | __metrics Screen width/height:1600/1200 22:31:07 INFO - PROCESS | 25997 | colorDepth:24 22:31:07 INFO - PROCESS | 25997 | Browser inner width/height: 1024/697 22:31:07 INFO - PROCESS | 25997 | __metrics 22:31:08 INFO - PROCESS | 25997 | 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" 22:31:08 ERROR - PROCESS | 25997 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:31:09 INFO - TEST-INFO | 25997: exit 0 22:31:09 INFO - Browser initialized. 22:31:09 INFO - Running cycle 1/1 for dromaeo_css test... 22:31:09 INFO - TEST-INFO | started process 26093 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRoAutq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:31:21 INFO - PROCESS | 26093 | RSS: Main: 145399808 22:31:21 INFO - PROCESS | 26093 | 22:32:12 INFO - PROCESS | 26093 | 1469683932232 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] 22:34:20 INFO - PROCESS | 26093 | Cycle 1(1): loaded http://localhost:54021/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54021/tests/dromaeo/cssquery-ext.html) 22:34:21 INFO - PROCESS | 26093 | RSS: Main: 253685760 22:34:21 INFO - PROCESS | 26093 | 22:37:23 INFO - PROCESS | 26093 | Cycle 1(1): loaded http://localhost:54021/tests/dromaeo/cssquery-ext.html (next: http://localhost:54021/tests/dromaeo/cssquery-jquery.html) 22:37:24 INFO - PROCESS | 26093 | RSS: Main: 226349056 22:37:24 INFO - PROCESS | 26093 | 22:40:26 INFO - PROCESS | 26093 | Cycle 1(1): loaded http://localhost:54021/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54021/tests/dromaeo/cssquery-mootools.html) 22:40:26 INFO - PROCESS | 26093 | RSS: Main: 225816576 22:40:26 INFO - PROCESS | 26093 | 22:43:28 INFO - PROCESS | 26093 | Cycle 1(1): loaded http://localhost:54021/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54021/tests/dromaeo/cssquery-prototype.html) 22:43:29 INFO - PROCESS | 26093 | RSS: Main: 220528640 22:43:29 INFO - PROCESS | 26093 | 22:46:31 INFO - PROCESS | 26093 | Cycle 1(1): loaded http://localhost:54021/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54021/tests/dromaeo/cssquery-yui.html) 22:46:32 INFO - PROCESS | 26093 | RSS: Main: 209920000 22:46:32 INFO - PROCESS | 26093 | 22:49:34 INFO - PROCESS | 26093 | Cycle 1(1): loaded http://localhost:54021/tests/dromaeo/cssquery-yui.html (next: http://localhost:54021/tests/dromaeo/cssquery-dojo.html) 22:49:35 INFO - PROCESS | 26093 | RSS: Main: 259641344 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | __start_tp_report 22:49:35 INFO - PROCESS | 26093 | _x_x_mozilla_page_load 22:49:35 INFO - PROCESS | 26093 | _x_x_mozilla_page_load_details 22:49:35 INFO - PROCESS | 26093 | |i|pagename|runs| 22:49:35 INFO - PROCESS | 26093 | |0;dojo.html;3324.8337583120847;3331.2837739885917;3335.149536868099;3346.3474622448603;3380.290139070795;5360.356757189138;5478.315210598676;5479.479449452306;5484.040292948733;5521.392646808853;5484.808031718891;5537.446255374471;5552.666839989593;5567.5864477820405;5578.832635020955;4888.9511104889025;4984.4517103118715;5005.674631148963;5047.26814611881;5076.84769456915;4897.51024897511;5055.4186268579215;5105.336306280178;5108.208227724708;5109.718965456898;4962.0324036812835;4980.87547811304;5005.449400565953;5049.166887463564;5081.79672813087;5098.668586541881;5120.48795120485;5139.486051394868;5141.434442211354;5171.586273098162;4894.608431325485;4902.509749025105;4949.653524253304;5024.84925452237;5031.647784655075;5352.678839269617;5364.597655175839;5376.1398176292;5418.539424148952;5434;5898.8820223595285;5940.673262970535;5946.405359464019;5947.910781338283;5949.851253718648;5858.794942177046;5897.174395584621;5929.614575052651;5991.400859913973;5991.490723288525;5554.750036248379;5587.189857470644;5597.384287728388;5598.664080155204;5605.159226116074;5873.207117039145;6081.010204948211;6085.56737057559;6113.938860611426;6118.449339559461;5654.6889921054735;5709.34342550612;5747.5114615257335;5763.7982670606325;5786.797462088806;5818.592698511148;5820.621659592113;5822.446867547576;5852;5853.970730146322;5718.085106382959;5765.962126817213;5785.942140578541;5788.82633520995;5795.1017592273265;5812.186293918855;5871.060630299132;5874.089516125007;5894.086416605433;5913.349531551526;4550.408446901948;4556.7721613919175;4558.772061396917;4560.612347950461;4581.839635612738;4022.939655905164;4031.0930040741187;4062.878113656595;4089.1208390196252;4136.028033412145;5628.09950407941;5644.012297847942;5652.886942261214;5656.462636049569;5672.290963629546;5920.881582368328;5942.821715348547;5958.6722730249385;5963.4334738199805;5995.130706047598;5480.534154596824;5536.197251398606;5565.638233514808;5639.069553523705;5655.802046928337;5139.152039913447;5139.717315547606;5141.71720555373;5143.5627971622835;5154.329937108152;6065.484433823174;6073.848153796191;6075.27096748393;6077.392260773887;6078.544109191828;5752.712364381848;5833.4749872511675;5913.05391137416;5913.556483263858;5928.110783382527;6078.939210607838;6117.388261173847;6118.296395914527;6123.724432400463;6129.877402452015;5832.008558544972;5849.795257165979;5851.414858514115;5851.970740146272;5854.443827836349;5622.803201887914;5768.942310577008;5837.708114594338;5898.262717160355;5960.5231581474245;5750.194972703741;5757.568182386254;5777.335606405317;5803.883922321615;5805.622634528742;5504.862378440571;5793.536517078539;5797.507211887037;5800.709964501673;5807.651540907559;5619.578531610064;5660.037793575185;5669.6314739541795;5739.139129130497;5745.425457454221;5809.883802323845;5841.94158058414;5877.471027607365;5880.529557635465;5884.293884733859;5508.944910551004;5533.695646739468;5704.942950570441;5710.828675139753;5728.341240757366 22:49:35 INFO - PROCESS | 26093 | |1;ext.html;599.3347384403312;643.4305639509033;650.9902351464729;654.188805880708;659.8913824774377;32513.51229731553;36101.2779744405;36184.638153618456;49939.75030124853;50861;48533.51466485334;49119.26321105177;49512.25731614028;49550.75224623872;49619.50380496194;8295.83408331833;9045.90954090459;9188.724338269829;9193.862092068624;9201.263898888074;8161.673533058671;8477.237048665618;8482.024567174793;10659.680209593724;10858.185636077287;11048.668539943814;11104.833427498594;11108.500117494692;11117.499712512916;11119.833202501968;10994.890051099506;11045.171612129043;11049.005589496985;11077.723056923562;11079.224454288164;8261.091279959199;8403.579821008925;10915.945420272927;10941.507632156492;10944.507497162567;45938.31092533615;46334.53665463336;46401.30398044032;46444;46497.53502464966;3826.4260360945805;3894.844206231747;4411.2941929291155;4477.820887164542;4498.908345808183;35699.46450803214;35932.82033589842;36154;36171.63828361709;36177.819110904544;4636.860894173146;4745.572898439123;4762.190427627312;4767.785449654774;4879;364271;366436;366749;366977;368048;70730;71131;71176.288237118;71185.28814711787;71360;40685;41320.58679413227;41497.79251103725;76521.23478765141;76947;19748.11133498996;19867.205311787366;19971.301004464774;20023.59952800965;20052.598948020957;13728.862711372758;14245.216513091673;14981;15078;15084;21716.34850954474;22516.774832251795;22628.547429051327;22667.433314167276;22692.20577279787;7921.118651860547;7977.0456238222005;8076.798080047928;8166;8436.325093992466;1043.7599352148895;1140.7889540435046;1170.2919733561243;1171.6719318590808;1195.9581414650445;33800.49299260513;34721.30557388838;34725.65274347275;35007.64992350095;35263;1176.670532250971;1238.6655602987144;1273.8598754137038;1294.3075454631726;1308.4373719300602;18277.984132618236;18312.175952082187;18367.908160459112;18417.26330946747;18433.53916152107;203386;564174;565614;567324;568765;65262.71580494755;66526.33473665298;66651.33348666452;66664.33335666581;66672.6666366665;1360.1906865415062;1473.0719646622774;1491.7762335649522;1509.124707669549;1518.5444366689949;31479.37041259116;34884.47673284852;35522.28955420929;35594;35765.46351804777;55393;55723.72138139283;55982;56013.43986560082;56035;374382;374545;376853;377035;377300;28933.85533072321;30837.845810770803;31390.843045784626;31590.368192636477;31680;52621.21068183904;52719.736401319286;53266;53377.73311133419;53740;43737.56262437335;44017.559824402626;44235.55764442314;44996;45396.773016134706;43780.34329485124;44159.55840441555;44463.77768111139;44909.775451122536;45537.77231113823;44479.77760111179;44925.55074449214;45791.31313030374;45967.540324596324;46043.30935036044;39495;41191.7940410296;41239.793801030806;41370.16618648391;41695.791521043415;30147.69852301449;30289;30702.692973070876;30732.385352293277;30827.537586935767 22:49:35 INFO - PROCESS | 26093 | |2;jquery.html;421.1072526244359;421.7553818785104;425.98296068157276;426.3668452348264;431.3228231676268;18903;19779.604407911833;20371.49071273217;21193.894030529827;21382.35852924411;19943.700844487306;20066.49833754157;20156.395308140778;20409.081591328388;20496.795032049675;5665.688387138705;5851.64890106595;5902.8194361127735;5908.763649454017;5908.822735317947;4144.792760361985;5715.142728590703;5771.192033115367;6193.1948846649875;6534.379233972789;6565.803025909207;6589.439897608685;6602.834929126786;6620.503462240327;6626.271110177877;6386.521010924199;6499.12261844652;6543.607383556954;6573.868522629545;6578.506612004095;3881.1849511602595;5468.507834294893;5761.625494342854;5782.653040817564;5789.739461724233;23167.536649267087;24351.634725479133;24530.386740331454;24726.381840454134;24853;2412.035185925626;2635.973640263592;2829.9292517687018;2895.898643547466;2997.8351190684502;14409.351579178858;15076.698466030617;15126.773098403533;15364.692706145814;15367.231638418034;1900.5628705397835;2016.8084032016934;2057.094878253564;2100.8634438761583;2101.2645574049116;78264.60867695682;82598;82646.58676706639;82656.58671706603;83444;10852.240343175901;10858.145358397654;11474.48364823585;11511.654650360502;12100.75798484043;16023.278952447168;16041.598960026093;16132.193390330436;16153.353865845234;16176.514704558882;8817.603207855662;9000.774980625536;9065.501397423204;9109.27125829932;9165.862512062324;8493.490390576586;8581.442206256574;8622.792809006743;8699.43453675509;9089.636414543476;6950.756723514653;6995.4053905417595;7011.3339232772805;7078.646067696594;7156.640238354696;1788.794288656795;1828.1136920163735;1832.7983921768862;1842.6959551674092;1859.7675290588677;998.5905778630727;999.2505620784411;1050.9527071281811;1139.2139423797719;1181.9468123934444;19041.61916761657;19841.107150178275;19982.20071197165;20046.799532004785;20049.498762531053;1073.5974009746344;1081.1404933078222;1121.85415895933;1150.424787606197;1165.1727273635813;11257.211995160424;11303.039241664386;11315.15136364776;11440.542378304775;11547.364894930859;503663;507451;509128;509742;518306;81100.59449702714;81647.59176204081;81959.5902020498;83915.5804220975;85418;1104.745908441063;1110.6001839337832;1129.8192289233684;1135.4209353229912;1142.0406858238953;17636;17775.111244437983;17920.193591288684;17937.192826322585;18439.354622588144;37211.81394093012;39529.604703952595;40043.79978100091;40609.18781624292;41812;54271.743695378405;55329.44670553404;55359;55366.72316638552;55412.16881746696;8437.493750374997;8581.06925941683;8621.827563448822;8693.956530217309;8826.646934122564;11983.940080299542;12414.937925310316;12614.180078294881;12891.290978996245;12963.35183240853;11132.721681958015;11154.609588664358;11193.440327983732;11418.942905285752;11519.769604607693;11483.8277425837;11645.708857278298;11685.357305348287;11859;11939.880601194225;11641;11859.58491452795;12242.816357754818;12365.75268494643;12434.813477798021;8834.469931804113;8903.020667726674;8921.821563568823;9066.138716821892;9067.047959964373;11503.884961150616;12075.396230188631;12196.93901530522;12214.75570488603;12355.196912200678 22:49:35 INFO - PROCESS | 26093 | |3;mootools.html;1429.8141241638587;1625.7236269834132;1661.783968084149;1897.5445892985688;1971.8915459649722;9052;9331.300152488566;9344.159025687695;9620.951895240514;9629.711108666734;9310.86033709495;9532.332736708433;9548.904510954888;9568.76078098048;9579.425234485936;2107.968380474294;2362.7164740231196;2444.144549407702;2509.4102885821862;2536.03630620364;2495.987520062406;2518.8362756420865;2524.507720994407;2528.190978886757;2529.53203657324;2402.9759702403016;2412.372783076404;2422.697162854643;2422.9878850605724;2423.7333893271725;2039.479932617188;2423.5758742220128;2492.950140997188;2493.800495960319;2517.1944977607172;2386.8925898334614;2388.7133543974833;2424.2121310574134;2425.38152771044;2427.5873101572774;9708.223342132613;9743.707688769347;9744.707658770178;9775.169110625606;9805.607775688964;5570.108782594765;5707.3721890592005;5714.314282286111;5718.285214348207;5721.828345149652;3456.9654303456896;3555.182308069133;3586.8744593939346;3587.4080776671817;3621.4024685926793;1111.8499002634703;1124.7637996020765;1147.2370873369282;1151.3494875395374;1176.7940610393148;13952.651183720387;13966.581002569837;13978.720425591431;14018.92990535051;14085.366158522891;4904.141775189328;5035.169197082514;5072.213806859942;5103.974480127576;5119.6160287978555;11484.310941343547;11564.42177891102;11572.537098515964;11579.189456737948;11581.363025033535;5040.621953353513;5610.438956104357;5656.377798442168;5660.235868157746;5670.121131224667;4960.702357858468;5050.4949505049935;5052.898942021139;5098.796048158107;5099.745012749348;4209.2633789086785;4263.55232700562;4274.187904298174;4453.198424283595;4494.07871386365;1285.916415432994;1288.3171918883006;1290.1356091418772;1295.3005377096451;1300.219868079164;1033.834586466162;1044.7858189071221;1046.8900765419673;1055.6621880998161;1067.4395942130443;6497.252815926229;6708.672721653301;6728.831779205461;6758.425533829582;6797.5581587196675;466.9579737823612;470.602341021836;498.19044053413205;498.2799854210703;509.81901424994334;4100.323446631272;4119.546849846514;4152.979235103866;4182.623563879205;4258.254805409041;11827.822582661267;12229.449674764657;12326.50693972231;12345.321007344503;12346.320952347527;12060.939695301468;12179.634610961686;12199.51201951912;12200.145989780807;12273.018158547295;8623.913760862311;8956.462612243156;8975.23710484603;9165.54172291396;9172.266218702487;6083.8174854754425;6334.208223972004;6377.681115944091;6398.648074355769;6492.35076492347;10475.633352832614;10803.729906752395;10820.242583019113;10908.509117089592;10951.616693415692;10667.146628269875;10990.395528246023;11087.113030957666;11117.666470005914;11136.053435458076;7902.920970790218;8175.918240817516;8176.264136227768;8703.347248956481;8899.243564297107;9248.722538323862;9468.573914174016;9506.382085164441;9661.468619225729;9680.080392362714;8430.241278284984;8716.128387161487;8722.25860801839;8772;8847.557622118997;8360.540170290693;8364;8507.447015943944;8527.147285271423;8684.78288042804;7225.747098851515;7234.131904171533;7236.4210863131875;7245.058142441449;7247.420206383582;5590.357108932525;5726.141078838041;5821.912671310019;5858.9121163181735;5875.618084824473;5662.207290979349;5713.514351280021;5739.512141468022;5812.418758124154;5866 22:49:35 INFO - PROCESS | 26093 | |4;prototype.html;353.58453816765297;353.681686482166;356.3870143353433;360.4053312035143;363.97634153779984;5437.755301011454;5442.265294185284;5497.93827314757;5512.641678290918;5517.337919449672;5381.784728610861;5385.407605163438;5412.2422860799425;5417.783288668448;5436.293281873351;4107.691923105764;4277.925206274954;4284.314509678452;4284.443022407083;4284.993026638753;4270.081932384537;4299.914001719963;4312.159128969852;4317.43873296471;4319.343603683743;4060.5127384713874;4097.89755256118;4276.786160691953;4290.6138447539715;4308.784560771965;4167.4790651168605;4183.4979802423595;4185.97907010463;4192.140611174717;4192.81132349045;3681.226942342111;3686.1521849974656;3687.4100143976834;3689.797061161635;3699.8335074921692;4992.201247800371;5052.9242061369105;5152.613553983429;5173.508516690946;5218.947810521884;4789.377380940456;4789.802549362659;4824.324594556765;4827.841318083635;4840.685355451885;5603.635763675389;5607.467290607386;5667.773289068432;5678.290213723285;5700.429957004308;3907.179492306619;4127.8968025799595;4129.442525259082;4140.523839758406;4152.771597562133;5885.70571471429;5889.087191485284;5911.349751527328;5929.911051334234;5932.9406705932815;4386.561343865652;4389.561043895585;4393.604575588212;4399.098184872094;4399.450068741407;4250.107477429712;4399.076193999296;4412.448443944507;4417;4417.491988421309;4908.680935739166;4918.975405122951;4924;4929.926051109164;4937.456879743226;5610.635308705003;5619.157126431068;5711.400302968212;5750.597458177971;5769.653820770767;1445.4651778841685;1513.8107736532934;1517.4613012380667;1518.180182701329;1522.4595268679682;966.7583104223944;990.3909095905966;991.306085739985;1002.644061358222;1003.8343673293971;4855.757212139379;4895.192293271571;4918.975405122951;4924.9507504924495;4947.876303092379;5100.719460429637;5128.358955130609;5136.486351364908;5167.638265321467;5602.411746766612;5373.844623405908;5515.503604675599;5537.833864984057;5559.722013899289;5562.137868630369;3074.431230222416;3077.7999430036975;3092.5361195820806;3093.381323735217;3139.4192074466296;5751.712414379264;5998.0806142035;6022.7289771959495;6023.849403764941;6029.879402411927;5936.198613187251;5939.792107276225;5943.603253235487;5947.226860508106;5978.521718262528;5891.499222566044;5958.136070269873;6029.6683682397015;6094;6109;5873.676947767743;5896.970515147397;5900.173975643499;5912.911306330322;5919.845630102089;5762.596618236851;5779.248697669276;5783;5800.332961709458;5803.216565763568;5702.458266464845;5962.52299816005;5992.1910542078;6002.909956350745;6009.549283803644;4263.507772279678;4307.483102027777;4308.052228509723;4342.761148136883;4369.890752731207;5728.3985181557;5858.576774535199;5865.648061116347;5888.939990801697;5902.321233058082;5626.212330273846;5669.121286200645;5683.715814209356;5710.714464276686;5726.599138060463;5632.127020311858;5641.294838145232;5659.9717001416375;5692;5771.769129234784;5295.25866378715;5500.3949565548555;5562.777488900399;5672.943270567406;5703.08750599902;5685.545156387562;5724.771009159587;5773.595848290579;5778.226451303482;5786.640139567283;5373.462653734595;5495;5608.635438696471;5614.399896029775;5640.153976903497 22:49:35 INFO - PROCESS | 26093 | |5;yui.html;760.6995236881429;782.6126067596541;786.1941509952298;803.7468197517782;831.7671052105412;16504.66990660186;16922.323107075703;16940.23768930398;17129.229184686686;17444.56388590286;14263.358148883299;14300.284985750723;14399;14402.567922962302;14404.91963102769;734.5776178697257;875.7810547363159;896.708569714842;901.625825282507;902.2376092202112;894.369469523984;896.4755617963499;897.5691667999364;899.1817446124029;899.8245342158282;757.8408534207796;938.9718308450723;941.3551717073826;941.4586612697692;942.2838642631615;902.9503377314245;926.8887733471959;929.4887811703603;929.6885543342988;929.9674511392136;688.4733179117978;688.6006116452446;688.9758858439981;689.1351354050685;689.2005273882272;14847.777283340758;14967.850321496864;14986.250687465583;14997.625059373602;14998.325075371635;15151;15266.694666106616;15404.768928466083;15466.072035677895;15634.843651563453;12972.156809807333;13004.21974681522;13214.20714757108;13337.399817008258;13419.530316438877;4738.857834264978;4785.688930219525;4819.975900120512;4831.9033619327765;4915.852524424273;67041.66479167574;67620.6618966902;69245;69511.304886951;76934;11572.24780389272;12126.696832579257;12242;12334.938325308316;12876.163049401946;22253;22322.776772232395;22344.55310893773;22435.775642243694;22438.663420048713;9312;9427.952860235655;9613.471259080678;9748.561314740711;9951.651692190884;5077.492250774967;5092.719900405513;5245.239440281138;5288.68267903927;5335.786568537215;3518.5601799775027;3680.4479328100997;3687.2625474905126;3697.4453831925425;3698.1494256320657;622.7446746833867;630.8454428665007;723.9276072392719;731.0350337554484;732.9120505539263;730.1420830524113;739.6671497826;744.4863044499278;766.5362455714212;798.8641930871765;10833.891661083446;11040.889591104147;11115.054865398522;11323.207375483636;11413.942930285462;749;763.4846478626905;774.8643987302199;783.7687882074802;814.6741303478656;5073.162928116893;5159.226116082617;5167.974160129251;5182.948170518246;5425.945740542623;77028.40336050923;120973;124320.37839810744;130445;130610;70407.64796175987;102362;110473;111379;113097;97022;97911.51044244732;98920.50539747255;99309.00690992997;100175.49912250393;10425.478726063819;10539.683809485728;10626.36241825493;10690.358578485315;10694.73263168427;55261.72369138129;56359.43640563706;57135.42864571467;57665;60665.393346065975;67364.6631766838;74449;83877.58061209654;84248.15751842648;85557;14075;14168.433262669378;14203.360848762037;14623.41506339777;14857.257137143315;15827.604309892346;16191.514254572381;16779;17175.57061073483;17195.312187512358;14288.142711437347;14438.422463101359;14592.27038648042;15056;15300.540983770505;14873.702525949637;15332.233388330762;15478.922605387352;15879.12664803447;15916.283767230734;13790.793138102736;13951.58145255644;14176.929115354356;15028.924855375653;15693.764593530877;12039.569566172227;12354.135210535176;12403.069769767124;12415.503379865066;12606.55877044299;11914.463849126636;11981.76036479248;12066.396680166132;12070.577529786415;12087.153899227322 22:49:35 INFO - PROCESS | 26093 | __end_tp_report 22:49:35 INFO - PROCESS | 26093 | __start_cc_report 22:49:35 INFO - PROCESS | 26093 | _x_x_mozilla_cycle_collect,838 22:49:35 INFO - PROCESS | 26093 | __end_cc_report 22:49:35 INFO - PROCESS | 26093 | __startTimestamp1469684975239__endTimestamp 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | ------- Summary: start ------- 22:49:35 INFO - PROCESS | 26093 | Number of tests: 6 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | [#0] dojo.html Cycles:165 Average:5485.21 Median:5672.29 stddev:594.74 (10.5%) stddev-sans-first:571.91 22:49:35 INFO - PROCESS | 26093 | Values: 3324.8 3331.3 3335.1 3346.3 3380.3 5360.4 5478.3 5479.5 5484.0 5521.4 5484.8 5537.4 5552.7 5567.6 5578.8 4889.0 4984.5 5005.7 5047.3 5076.8 4897.5 5055.4 5105.3 5108.2 5109.7 4962.0 4980.9 5005.4 5049.2 5081.8 5098.7 5120.5 5139.5 5141.4 5171.6 4894.6 4902.5 4949.7 5024.8 5031.6 5352.7 5364.6 5376.1 5418.5 5434.0 5898.9 5940.7 5946.4 5947.9 5949.9 5858.8 5897.2 5929.6 5991.4 5991.5 5554.8 5587.2 5597.4 5598.7 5605.2 5873.2 6081.0 6085.6 6113.9 6118.4 5654.7 5709.3 5747.5 5763.8 5786.8 5818.6 5820.6 5822.4 5852.0 5854.0 5718.1 5766.0 5785.9 5788.8 5795.1 5812.2 5871.1 5874.1 5894.1 5913.3 4550.4 4556.8 4558.8 4560.6 4581.8 4022.9 4031.1 4062.9 4089.1 4136.0 5628.1 5644.0 5652.9 5656.5 5672.3 5920.9 5942.8 5958.7 5963.4 5995.1 5480.5 5536.2 5565.6 5639.1 5655.8 5139.2 5139.7 5141.7 5143.6 5154.3 6065.5 6073.8 6075.3 6077.4 6078.5 5752.7 5833.5 5913.1 5913.6 5928.1 6078.9 6117.4 6118.3 6123.7 6129.9 5832.0 5849.8 5851.4 5852.0 5854.4 5622.8 5768.9 5837.7 5898.3 5960.5 5750.2 5757.6 5777.3 5803.9 5805.6 5504.9 5793.5 5797.5 5800.7 5807.7 5619.6 5660.0 5669.6 5739.1 5745.4 5809.9 5841.9 5877.5 5880.5 5884.3 5508.9 5533.7 5704.9 5710.8 5728.3 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | [#1] ext.html Cycles:180 Average:60126.22 Median:21694.92 stddev:112244.49 (517.4%) stddev-sans-first:112470.38 22:49:35 INFO - PROCESS | 26093 | Values: 599.3 643.4 651.0 654.2 659.9 32513.5 36101.3 36184.6 49939.8 50861.0 48533.5 49119.3 49512.3 49550.8 49619.5 8295.8 9045.9 9188.7 9193.9 9201.3 8161.7 8477.2 8482.0 10659.7 10858.2 11048.7 11104.8 11108.5 11117.5 11119.8 10994.9 11045.2 11049.0 11077.7 11079.2 8261.1 8403.6 10915.9 10941.5 10944.5 45938.3 46334.5 46401.3 46444.0 46497.5 3826.4 3894.8 4411.3 4477.8 4498.9 35699.5 35932.8 36154.0 36171.6 36177.8 4636.9 4745.6 4762.2 4767.8 4879.0 364271.0 366436.0 366749.0 366977.0 368048.0 70730.0 71131.0 71176.3 71185.3 71360.0 40685.0 41320.6 41497.8 76521.2 76947.0 19748.1 19867.2 19971.3 20023.6 20052.6 13728.9 14245.2 14981.0 15078.0 15084.0 21716.3 22516.8 22628.5 22667.4 22692.2 7921.1 7977.0 8076.8 8166.0 8436.3 1043.8 1140.8 1170.3 1171.7 1196.0 33800.5 34721.3 34725.7 35007.6 35263.0 1176.7 1238.7 1273.9 1294.3 1308.4 18278.0 18312.2 18367.9 18417.3 18433.5 203386.0 564174.0 565614.0 567324.0 568765.0 65262.7 66526.3 66651.3 66664.3 66672.7 1360.2 1473.1 1491.8 1509.1 1518.5 31479.4 34884.5 35522.3 35594.0 35765.5 55393.0 55723.7 55982.0 56013.4 56035.0 374382.0 374545.0 376853.0 377035.0 377300.0 28933.9 30837.8 31390.8 31590.4 31680.0 52621.2 52719.7 53266.0 53377.7 53740.0 43737.6 44017.6 44235.6 44996.0 45396.8 43780.3 44159.6 44463.8 44909.8 45537.8 44479.8 44925.6 45791.3 45967.5 46043.3 39495.0 41191.8 41239.8 41370.2 41695.8 30147.7 30289.0 30702.7 30732.4 30827.5 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | [#2] jquery.html Cycles:180 Average:29676.01 Median:24441.01 stddev:83637.30 (342.2%) stddev-sans-first:83843.08 22:49:35 INFO - PROCESS | 26093 | Values: 421.1 421.8 426.0 426.4 431.3 18903.0 19779.6 20371.5 21193.9 21382.4 19943.7 20066.5 20156.4 20409.1 20496.8 5665.7 5851.6 5902.8 5908.8 5908.8 4144.8 5715.1 5771.2 6193.2 6534.4 6565.8 6589.4 6602.8 6620.5 6626.3 6386.5 6499.1 6543.6 6573.9 6578.5 3881.2 5468.5 5761.6 5782.7 5789.7 23167.5 24351.6 24530.4 24726.4 24853.0 2412.0 2636.0 2829.9 2895.9 2997.8 14409.4 15076.7 15126.8 15364.7 15367.2 1900.6 2016.8 2057.1 2100.9 2101.3 78264.6 82598.0 82646.6 82656.6 83444.0 10852.2 10858.1 11474.5 11511.7 12100.8 16023.3 16041.6 16132.2 16153.4 16176.5 8817.6 9000.8 9065.5 9109.3 9165.9 8493.5 8581.4 8622.8 8699.4 9089.6 6950.8 6995.4 7011.3 7078.6 7156.6 1788.8 1828.1 1832.8 1842.7 1859.8 998.6 999.3 1051.0 1139.2 1181.9 19041.6 19841.1 19982.2 20046.8 20049.5 1073.6 1081.1 1121.9 1150.4 1165.2 11257.2 11303.0 11315.2 11440.5 11547.4 503663.0 507451.0 509128.0 509742.0 518306.0 81100.6 81647.6 81959.6 83915.6 85418.0 1104.7 1110.6 1129.8 1135.4 1142.0 17636.0 17775.1 17920.2 17937.2 18439.4 37211.8 39529.6 40043.8 40609.2 41812.0 54271.7 55329.4 55359.0 55366.7 55412.2 8437.5 8581.1 8621.8 8694.0 8826.6 11983.9 12414.9 12614.2 12891.3 12963.4 11132.7 11154.6 11193.4 11418.9 11519.8 11483.8 11645.7 11685.4 11859.0 11939.9 11641.0 11859.6 12242.8 12365.8 12434.8 8834.5 8903.0 8921.8 9066.1 9067.0 11503.9 12075.4 12196.9 12214.8 12355.2 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | [#3] mootools.html Cycles:180 Average:6318.36 Median:468.78 stddev:3715.51 (792.6%) stddev-sans-first:3707.77 22:49:35 INFO - PROCESS | 26093 | Values: 1429.8 1625.7 1661.8 1897.5 1971.9 9052.0 9331.3 9344.2 9621.0 9629.7 9310.9 9532.3 9548.9 9568.8 9579.4 2108.0 2362.7 2444.1 2509.4 2536.0 2496.0 2518.8 2524.5 2528.2 2529.5 2403.0 2412.4 2422.7 2423.0 2423.7 2039.5 2423.6 2493.0 2493.8 2517.2 2386.9 2388.7 2424.2 2425.4 2427.6 9708.2 9743.7 9744.7 9775.2 9805.6 5570.1 5707.4 5714.3 5718.3 5721.8 3457.0 3555.2 3586.9 3587.4 3621.4 1111.8 1124.8 1147.2 1151.3 1176.8 13952.7 13966.6 13978.7 14018.9 14085.4 4904.1 5035.2 5072.2 5104.0 5119.6 11484.3 11564.4 11572.5 11579.2 11581.4 5040.6 5610.4 5656.4 5660.2 5670.1 4960.7 5050.5 5052.9 5098.8 5099.7 4209.3 4263.6 4274.2 4453.2 4494.1 1285.9 1288.3 1290.1 1295.3 1300.2 1033.8 1044.8 1046.9 1055.7 1067.4 6497.3 6708.7 6728.8 6758.4 6797.6 467.0 470.6 498.2 498.3 509.8 4100.3 4119.5 4153.0 4182.6 4258.3 11827.8 12229.4 12326.5 12345.3 12346.3 12060.9 12179.6 12199.5 12200.1 12273.0 8623.9 8956.5 8975.2 9165.5 9172.3 6083.8 6334.2 6377.7 6398.6 6492.4 10475.6 10803.7 10820.2 10908.5 10951.6 10667.1 10990.4 11087.1 11117.7 11136.1 7902.9 8175.9 8176.3 8703.3 8899.2 9248.7 9468.6 9506.4 9661.5 9680.1 8430.2 8716.1 8722.3 8772.0 8847.6 8360.5 8364.0 8507.4 8527.1 8684.8 7225.7 7234.1 7236.4 7245.1 7247.4 5590.4 5726.1 5821.9 5858.9 5875.6 5662.2 5713.5 5739.5 5812.4 5866.0 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | [#4] prototype.html Cycles:180 Average:4778.09 Median:5398.82 stddev:1382.76 (25.6%) stddev-sans-first:1346.17 22:49:35 INFO - PROCESS | 26093 | Values: 353.6 353.7 356.4 360.4 364.0 5437.8 5442.3 5497.9 5512.6 5517.3 5381.8 5385.4 5412.2 5417.8 5436.3 4107.7 4277.9 4284.3 4284.4 4285.0 4270.1 4299.9 4312.2 4317.4 4319.3 4060.5 4097.9 4276.8 4290.6 4308.8 4167.5 4183.5 4186.0 4192.1 4192.8 3681.2 3686.2 3687.4 3689.8 3699.8 4992.2 5052.9 5152.6 5173.5 5218.9 4789.4 4789.8 4824.3 4827.8 4840.7 5603.6 5607.5 5667.8 5678.3 5700.4 3907.2 4127.9 4129.4 4140.5 4152.8 5885.7 5889.1 5911.3 5929.9 5932.9 4386.6 4389.6 4393.6 4399.1 4399.5 4250.1 4399.1 4412.4 4417.0 4417.5 4908.7 4919.0 4924.0 4929.9 4937.5 5610.6 5619.2 5711.4 5750.6 5769.7 1445.5 1513.8 1517.5 1518.2 1522.5 966.8 990.4 991.3 1002.6 1003.8 4855.8 4895.2 4919.0 4925.0 4947.9 5100.7 5128.4 5136.5 5167.6 5602.4 5373.8 5515.5 5537.8 5559.7 5562.1 3074.4 3077.8 3092.5 3093.4 3139.4 5751.7 5998.1 6022.7 6023.8 6029.9 5936.2 5939.8 5943.6 5947.2 5978.5 5891.5 5958.1 6029.7 6094.0 6109.0 5873.7 5897.0 5900.2 5912.9 5919.8 5762.6 5779.2 5783.0 5800.3 5803.2 5702.5 5962.5 5992.2 6002.9 6009.5 4263.5 4307.5 4308.1 4342.8 4369.9 5728.4 5858.6 5865.6 5888.9 5902.3 5626.2 5669.1 5683.7 5710.7 5726.6 5632.1 5641.3 5660.0 5692.0 5771.8 5295.3 5500.4 5562.8 5672.9 5703.1 5685.5 5724.8 5773.6 5778.2 5786.6 5373.5 5495.0 5608.6 5614.4 5640.2 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - PROCESS | 26093 | [#5] yui.html Cycles:180 Average:21962.03 Median:3683.86 stddev:31331.73 (850.5%) stddev-sans-first:31379.18 22:49:35 INFO - PROCESS | 26093 | Values: 760.7 782.6 786.2 803.7 831.8 16504.7 16922.3 16940.2 17129.2 17444.6 14263.4 14300.3 14399.0 14402.6 14404.9 734.6 875.8 896.7 901.6 902.2 894.4 896.5 897.6 899.2 899.8 757.8 939.0 941.4 941.5 942.3 903.0 926.9 929.5 929.7 930.0 688.5 688.6 689.0 689.1 689.2 14847.8 14967.9 14986.3 14997.6 14998.3 15151.0 15266.7 15404.8 15466.1 15634.8 12972.2 13004.2 13214.2 13337.4 13419.5 4738.9 4785.7 4820.0 4831.9 4915.9 67041.7 67620.7 69245.0 69511.3 76934.0 11572.2 12126.7 12242.0 12334.9 12876.2 22253.0 22322.8 22344.6 22435.8 22438.7 9312.0 9428.0 9613.5 9748.6 9951.7 5077.5 5092.7 5245.2 5288.7 5335.8 3518.6 3680.4 3687.3 3697.4 3698.1 622.7 630.8 723.9 731.0 732.9 730.1 739.7 744.5 766.5 798.9 10833.9 11040.9 11115.1 11323.2 11413.9 749.0 763.5 774.9 783.8 814.7 5073.2 5159.2 5168.0 5182.9 5425.9 77028.4 120973.0 124320.4 130445.0 130610.0 70407.6 102362.0 110473.0 111379.0 113097.0 97022.0 97911.5 98920.5 99309.0 100175.5 10425.5 10539.7 10626.4 10690.4 10694.7 55261.7 56359.4 57135.4 57665.0 60665.4 67364.7 74449.0 83877.6 84248.2 85557.0 14075.0 14168.4 14203.4 14623.4 14857.3 15827.6 16191.5 16779.0 17175.6 17195.3 14288.1 14438.4 14592.3 15056.0 15300.5 14873.7 15332.2 15478.9 15879.1 15916.3 13790.8 13951.6 14176.9 15028.9 15693.8 12039.6 12354.1 12403.1 12415.5 12606.6 11914.5 11981.8 12066.4 12070.6 12087.2 22:49:35 INFO - PROCESS | 26093 | -------- Summary: end -------- 22:49:35 INFO - PROCESS | 26093 | 22:49:35 INFO - TEST-INFO | 26093: exit 0 22:49:36 INFO - TEST-OK | dromaeo_css | took 1112438ms 22:49:36 INFO - TEST-START | kraken 22:49:36 INFO - operating with platform_type : linux_ 22:49:36 INFO - Initialising browser for kraken test... 22:49:36 INFO - TEST-INFO | started process 26280 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6cG9Rl/profile http://localhost:54021/getInfo.html) 22:49:39 INFO - PROCESS | 26280 | __metrics Screen width/height:1600/1200 22:49:39 INFO - PROCESS | 26280 | colorDepth:24 22:49:39 INFO - PROCESS | 26280 | Browser inner width/height: 1024/697 22:49:39 INFO - PROCESS | 26280 | __metrics 22:49:40 INFO - PROCESS | 26280 | 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" 22:49:40 ERROR - PROCESS | 26280 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:49:41 INFO - TEST-INFO | 26280: exit 0 22:49:41 INFO - Browser initialized. 22:49:41 INFO - Running cycle 1/1 for kraken test... 22:49:41 INFO - TEST-INFO | started process 26378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6cG9Rl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:53 INFO - PROCESS | 26378 | RSS: Main: 145453056 22:49:53 INFO - PROCESS | 26378 | 22:49:55 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54021/tests/kraken/driver.html?testName=audio-beat-detection) 22:49:56 INFO - PROCESS | 26378 | RSS: Main: 194465792 22:49:56 INFO - PROCESS | 26378 | 22:49:59 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54021/tests/kraken/driver.html?testName=audio-dft) 22:50:00 INFO - PROCESS | 26378 | RSS: Main: 191152128 22:50:00 INFO - PROCESS | 26378 | 22:50:02 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54021/tests/kraken/driver.html?testName=audio-fft) 22:50:03 INFO - PROCESS | 26378 | RSS: Main: 277372928 22:50:03 INFO - PROCESS | 26378 | 22:50:05 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54021/tests/kraken/driver.html?testName=audio-oscillator) 22:50:06 INFO - PROCESS | 26378 | RSS: Main: 186269696 22:50:06 INFO - PROCESS | 26378 | 22:50:08 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54021/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:50:08 INFO - PROCESS | 26378 | RSS: Main: 186675200 22:50:08 INFO - PROCESS | 26378 | 22:50:11 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54021/tests/kraken/driver.html?testName=imaging-darkroom) 22:50:12 INFO - PROCESS | 26378 | RSS: Main: 222449664 22:50:12 INFO - PROCESS | 26378 | 22:50:15 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54021/tests/kraken/driver.html?testName=imaging-desaturate) 22:50:16 INFO - PROCESS | 26378 | RSS: Main: 222359552 22:50:16 INFO - PROCESS | 26378 | 22:50:19 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54021/tests/kraken/driver.html?testName=json-parse-financial) 22:50:19 INFO - PROCESS | 26378 | RSS: Main: 221253632 22:50:19 INFO - PROCESS | 26378 | 22:50:21 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54021/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:50:21 INFO - PROCESS | 26378 | RSS: Main: 200142848 22:50:21 INFO - PROCESS | 26378 | 22:50:23 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:50:24 INFO - PROCESS | 26378 | RSS: Main: 202633216 22:50:24 INFO - PROCESS | 26378 | 22:50:26 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:50:26 INFO - PROCESS | 26378 | RSS: Main: 209641472 22:50:26 INFO - PROCESS | 26378 | 22:50:29 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:50:29 INFO - PROCESS | 26378 | RSS: Main: 216616960 22:50:29 INFO - PROCESS | 26378 | 22:50:32 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:50:33 INFO - PROCESS | 26378 | RSS: Main: 215207936 22:50:33 INFO - PROCESS | 26378 | 22:50:34 INFO - PROCESS | 26378 | Cycle 1(1): loaded http://localhost:54021/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54021/tests/kraken/driver.html?testName=ai-astar) 22:50:35 INFO - PROCESS | 26378 | RSS: Main: 257949696 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | __start_tp_report 22:50:35 INFO - PROCESS | 26378 | _x_x_mozilla_page_load 22:50:35 INFO - PROCESS | 26378 | _x_x_mozilla_page_load_details 22:50:35 INFO - PROCESS | 26378 | |i|pagename|runs| 22:50:35 INFO - PROCESS | 26378 | |0;ai-astar;110;104;111;111;111;109;110;110;110;109 22:50:35 INFO - PROCESS | 26378 | |1;audio-beat-detection;150;129;134;178;130;154;158;124;177;122 22:50:35 INFO - PROCESS | 26378 | |2;audio-dft;161;164;161;161;161;160;160;162;162;161 22:50:35 INFO - PROCESS | 26378 | |3;audio-fft;73;74;75;116;74;75;74;73;99;75 22:50:35 INFO - PROCESS | 26378 | |4;audio-oscillator;84;83;119;84;83;84;123;83;83;136 22:50:35 INFO - PROCESS | 26378 | |5;imaging-gaussian-blur;116;116;117;117;117;116;117;116;116;117 22:50:35 INFO - PROCESS | 26378 | |6;imaging-darkroom;145;146;146;146;146;146;145;146;145;145 22:50:35 INFO - PROCESS | 26378 | |7;imaging-desaturate;94;94;95;94;95;94;95;95;95;95 22:50:35 INFO - PROCESS | 26378 | |8;json-parse-financial;51;50;50;50;52;50;50;51;52;51 22:50:35 INFO - PROCESS | 26378 | |9;json-stringify-tinderbox;59;59;60;60;59;59;60;60;60;59 22:50:35 INFO - PROCESS | 26378 | |10;stanford-crypto-aes;76;81;79;75;81;79;77;76;113;82 22:50:35 INFO - PROCESS | 26378 | |11;stanford-crypto-ccm;118;117;116;117;131;113;116;117;120;118 22:50:35 INFO - PROCESS | 26378 | |12;stanford-crypto-pbkdf2;152;155;153;154;152;151;155;154;149;150 22:50:35 INFO - PROCESS | 26378 | |13;stanford-crypto-sha256-iterative;61;61;63;64;61;61;61;59;61;60 22:50:35 INFO - PROCESS | 26378 | __end_tp_report 22:50:35 INFO - PROCESS | 26378 | __start_cc_report 22:50:35 INFO - PROCESS | 26378 | _x_x_mozilla_cycle_collect,1587 22:50:35 INFO - PROCESS | 26378 | __end_cc_report 22:50:35 INFO - PROCESS | 26378 | __startTimestamp1469685035034__endTimestamp 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | ------- Summary: start ------- 22:50:35 INFO - PROCESS | 26378 | Number of tests: 14 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:2.07 (1.9%) stddev-sans-first:2.19 22:50:35 INFO - PROCESS | 26378 | Values: 110.0 104.0 111.0 111.0 111.0 109.0 110.0 110.0 110.0 109.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:152.00 stddev:20.97 (13.8%) stddev-sans-first:22.18 22:50:35 INFO - PROCESS | 26378 | Values: 150.0 129.0 134.0 178.0 130.0 154.0 158.0 124.0 177.0 122.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 22:50:35 INFO - PROCESS | 26378 | Values: 161.0 164.0 161.0 161.0 161.0 160.0 160.0 162.0 162.0 161.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#3] audio-fft Cycles:10 Average:80.80 Median:74.50 stddev:14.65 (19.7%) stddev-sans-first:15.26 22:50:35 INFO - PROCESS | 26378 | Values: 73.0 74.0 75.0 116.0 74.0 75.0 74.0 73.0 99.0 75.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:83.00 stddev:20.99 (25.3%) stddev-sans-first:21.80 22:50:35 INFO - PROCESS | 26378 | Values: 84.0 83.0 119.0 84.0 83.0 84.0 123.0 83.0 83.0 136.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 22:50:35 INFO - PROCESS | 26378 | Values: 116.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:50:35 INFO - PROCESS | 26378 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 145.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:50:35 INFO - PROCESS | 26378 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 22:50:35 INFO - PROCESS | 26378 | Values: 51.0 50.0 50.0 50.0 52.0 50.0 50.0 51.0 52.0 51.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 22:50:35 INFO - PROCESS | 26378 | Values: 59.0 59.0 60.0 60.0 59.0 59.0 60.0 60.0 60.0 59.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:79.00 stddev:11.19 (14.2%) stddev-sans-first:11.66 22:50:35 INFO - PROCESS | 26378 | Values: 76.0 81.0 79.0 75.0 81.0 79.0 77.0 76.0 113.0 82.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#11] stanford-crypto-ccm Cycles:10 Average:118.30 Median:117.50 stddev:4.81 (4.1%) stddev-sans-first:5.10 22:50:35 INFO - PROCESS | 26378 | Values: 118.0 117.0 116.0 117.0 131.0 113.0 116.0 117.0 120.0 118.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:153.50 stddev:2.07 (1.3%) stddev-sans-first:2.19 22:50:35 INFO - PROCESS | 26378 | Values: 152.0 155.0 153.0 154.0 152.0 151.0 155.0 154.0 149.0 150.0 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - PROCESS | 26378 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.40 (2.3%) stddev-sans-first:1.48 22:50:35 INFO - PROCESS | 26378 | Values: 61.0 61.0 63.0 64.0 61.0 61.0 61.0 59.0 61.0 60.0 22:50:35 INFO - PROCESS | 26378 | -------- Summary: end -------- 22:50:35 INFO - PROCESS | 26378 | 22:50:35 INFO - TEST-INFO | 26378: exit 0 22:50:36 INFO - TEST-OK | kraken | took 59997ms 22:50:36 INFO - SUITE-END | took 1172s 22:50:36 INFO - Completed test suite (00:19:32) 22:50:36 INFO - javascript benchmark 22:50:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.8337583120847, 3331.2837739885917, 3335.149536868099, 3346.3474622448603, 3380.290139070795, 5360.356757189138, 5478.315210598676, 5479.479449452306, 5484.040292948733, 5521.392646808853, 5484.808031718891, 5537.446255374471, 5552.666839989593, 5567.5864477820405, 5578.832635020955, 4888.9511104889025, 4984.4517103118715, 5005.674631148963, 5047.26814611881, 5076.84769456915, 4897.51024897511, 5055.4186268579215, 5105.336306280178, 5108.208227724708, 5109.718965456898, 4962.0324036812835, 4980.87547811304, 5005.449400565953, 5049.166887463564, 5081.79672813087, 5098.668586541881, 5120.48795120485, 5139.486051394868, 5141.434442211354, 5171.586273098162, 4894.608431325485, 4902.509749025105, 4949.653524253304, 5024.84925452237, 5031.647784655075, 5352.678839269617, 5364.597655175839, 5376.1398176292, 5418.539424148952, 5434.0, 5898.8820223595285, 5940.673262970535, 5946.405359464019, 5947.910781338283, 5949.851253718648, 5858.794942177046, 5897.174395584621, 5929.614575052651, 5991.400859913973, 5991.490723288525, 5554.750036248379, 5587.189857470644, 5597.384287728388, 5598.664080155204, 5605.159226116074, 5873.207117039145, 6081.010204948211, 6085.56737057559, 6113.938860611426, 6118.449339559461, 5654.6889921054735, 5709.34342550612, 5747.5114615257335, 5763.7982670606325, 5786.797462088806, 5818.592698511148, 5820.621659592113, 5822.446867547576, 5852.0, 5853.970730146322, 5718.085106382959, 5765.962126817213, 5785.942140578541, 5788.82633520995, 5795.1017592273265, 5812.186293918855, 5871.060630299132, 5874.089516125007, 5894.086416605433, 5913.349531551526, 4550.408446901948, 4556.7721613919175, 4558.772061396917, 4560.612347950461, 4581.839635612738, 4022.939655905164, 4031.0930040741187, 4062.878113656595, 4089.1208390196252, 4136.028033412145, 5628.09950407941, 5644.012297847942, 5652.886942261214, 5656.462636049569, 5672.290963629546, 5920.881582368328, 5942.821715348547, 5958.6722730249385, 5963.4334738199805, 5995.130706047598, 5480.534154596824, 5536.197251398606, 5565.638233514808, 5639.069553523705, 5655.802046928337, 5139.152039913447, 5139.717315547606, 5141.71720555373, 5143.5627971622835, 5154.329937108152, 6065.484433823174, 6073.848153796191, 6075.27096748393, 6077.392260773887, 6078.544109191828, 5752.712364381848, 5833.4749872511675, 5913.05391137416, 5913.556483263858, 5928.110783382527, 6078.939210607838, 6117.388261173847, 6118.296395914527, 6123.724432400463, 6129.877402452015, 5832.008558544972, 5849.795257165979, 5851.414858514115, 5851.970740146272, 5854.443827836349, 5622.803201887914, 5768.942310577008, 5837.708114594338, 5898.262717160355, 5960.5231581474245, 5750.194972703741, 5757.568182386254, 5777.335606405317, 5803.883922321615, 5805.622634528742, 5504.862378440571, 5793.536517078539, 5797.507211887037, 5800.709964501673, 5807.651540907559, 5619.578531610064, 5660.037793575185, 5669.6314739541795, 5739.139129130497, 5745.425457454221, 5809.883802323845, 5841.94158058414, 5877.471027607365, 5880.529557635465, 5884.293884733859, 5508.944910551004, 5533.695646739468, 5704.942950570441, 5710.828675139753, 5728.341240757366], "value": 5447.575543796241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.3347384403312, 643.4305639509033, 650.9902351464729, 654.188805880708, 659.8913824774377, 32513.51229731553, 36101.2779744405, 36184.638153618456, 49939.75030124853, 50861.0, 48533.51466485334, 49119.26321105177, 49512.25731614028, 49550.75224623872, 49619.50380496194, 8295.83408331833, 9045.90954090459, 9188.724338269829, 9193.862092068624, 9201.263898888074, 8161.673533058671, 8477.237048665618, 8482.024567174793, 10659.680209593724, 10858.185636077287, 11048.668539943814, 11104.833427498594, 11108.500117494692, 11117.499712512916, 11119.833202501968, 10994.890051099506, 11045.171612129043, 11049.005589496985, 11077.723056923562, 11079.224454288164, 8261.091279959199, 8403.579821008925, 10915.945420272927, 10941.507632156492, 10944.507497162567, 45938.31092533615, 46334.53665463336, 46401.30398044032, 46444.0, 46497.53502464966, 3826.4260360945805, 3894.844206231747, 4411.2941929291155, 4477.820887164542, 4498.908345808183, 35699.46450803214, 35932.82033589842, 36154.0, 36171.63828361709, 36177.819110904544, 4636.860894173146, 4745.572898439123, 4762.190427627312, 4767.785449654774, 4879.0, 364271.0, 366436.0, 366749.0, 366977.0, 368048.0, 70730.0, 71131.0, 71176.288237118, 71185.28814711787, 71360.0, 40685.0, 41320.58679413227, 41497.79251103725, 76521.23478765141, 76947.0, 19748.11133498996, 19867.205311787366, 19971.301004464774, 20023.59952800965, 20052.598948020957, 13728.862711372758, 14245.216513091673, 14981.0, 15078.0, 15084.0, 21716.34850954474, 22516.774832251795, 22628.547429051327, 22667.433314167276, 22692.20577279787, 7921.118651860547, 7977.0456238222005, 8076.798080047928, 8166.0, 8436.325093992466, 1043.7599352148895, 1140.7889540435046, 1170.2919733561243, 1171.6719318590808, 1195.9581414650445, 33800.49299260513, 34721.30557388838, 34725.65274347275, 35007.64992350095, 35263.0, 1176.670532250971, 1238.6655602987144, 1273.8598754137038, 1294.3075454631726, 1308.4373719300602, 18277.984132618236, 18312.175952082187, 18367.908160459112, 18417.26330946747, 18433.53916152107, 203386.0, 564174.0, 565614.0, 567324.0, 568765.0, 65262.71580494755, 66526.33473665298, 66651.33348666452, 66664.33335666581, 66672.6666366665, 1360.1906865415062, 1473.0719646622774, 1491.7762335649522, 1509.124707669549, 1518.5444366689949, 31479.37041259116, 34884.47673284852, 35522.28955420929, 35594.0, 35765.46351804777, 55393.0, 55723.72138139283, 55982.0, 56013.43986560082, 56035.0, 374382.0, 374545.0, 376853.0, 377035.0, 377300.0, 28933.85533072321, 30837.845810770803, 31390.843045784626, 31590.368192636477, 31680.0, 52621.21068183904, 52719.736401319286, 53266.0, 53377.73311133419, 53740.0, 43737.56262437335, 44017.559824402626, 44235.55764442314, 44996.0, 45396.773016134706, 43780.34329485124, 44159.55840441555, 44463.77768111139, 44909.775451122536, 45537.77231113823, 44479.77760111179, 44925.55074449214, 45791.31313030374, 45967.540324596324, 46043.30935036044, 39495.0, 41191.7940410296, 41239.793801030806, 41370.16618648391, 41695.791521043415, 30147.69852301449, 30289.0, 30702.692973070876, 30732.385352293277, 30827.537586935767], "value": 21972.575594164908, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.1072526244359, 421.7553818785104, 425.98296068157276, 426.3668452348264, 431.3228231676268, 18903.0, 19779.604407911833, 20371.49071273217, 21193.894030529827, 21382.35852924411, 19943.700844487306, 20066.49833754157, 20156.395308140778, 20409.081591328388, 20496.795032049675, 5665.688387138705, 5851.64890106595, 5902.8194361127735, 5908.763649454017, 5908.822735317947, 4144.792760361985, 5715.142728590703, 5771.192033115367, 6193.1948846649875, 6534.379233972789, 6565.803025909207, 6589.439897608685, 6602.834929126786, 6620.503462240327, 6626.271110177877, 6386.521010924199, 6499.12261844652, 6543.607383556954, 6573.868522629545, 6578.506612004095, 3881.1849511602595, 5468.507834294893, 5761.625494342854, 5782.653040817564, 5789.739461724233, 23167.536649267087, 24351.634725479133, 24530.386740331454, 24726.381840454134, 24853.0, 2412.035185925626, 2635.973640263592, 2829.9292517687018, 2895.898643547466, 2997.8351190684502, 14409.351579178858, 15076.698466030617, 15126.773098403533, 15364.692706145814, 15367.231638418034, 1900.5628705397835, 2016.8084032016934, 2057.094878253564, 2100.8634438761583, 2101.2645574049116, 78264.60867695682, 82598.0, 82646.58676706639, 82656.58671706603, 83444.0, 10852.240343175901, 10858.145358397654, 11474.48364823585, 11511.654650360502, 12100.75798484043, 16023.278952447168, 16041.598960026093, 16132.193390330436, 16153.353865845234, 16176.514704558882, 8817.603207855662, 9000.774980625536, 9065.501397423204, 9109.27125829932, 9165.862512062324, 8493.490390576586, 8581.442206256574, 8622.792809006743, 8699.43453675509, 9089.636414543476, 6950.756723514653, 6995.4053905417595, 7011.3339232772805, 7078.646067696594, 7156.640238354696, 1788.794288656795, 1828.1136920163735, 1832.7983921768862, 1842.6959551674092, 1859.7675290588677, 998.5905778630727, 999.2505620784411, 1050.9527071281811, 1139.2139423797719, 1181.9468123934444, 19041.61916761657, 19841.107150178275, 19982.20071197165, 20046.799532004785, 20049.498762531053, 1073.5974009746344, 1081.1404933078222, 1121.85415895933, 1150.424787606197, 1165.1727273635813, 11257.211995160424, 11303.039241664386, 11315.15136364776, 11440.542378304775, 11547.364894930859, 503663.0, 507451.0, 509128.0, 509742.0, 518306.0, 81100.59449702714, 81647.59176204081, 81959.5902020498, 83915.5804220975, 85418.0, 1104.745908441063, 1110.6001839337832, 1129.8192289233684, 1135.4209353229912, 1142.0406858238953, 17636.0, 17775.111244437983, 17920.193591288684, 17937.192826322585, 18439.354622588144, 37211.81394093012, 39529.604703952595, 40043.79978100091, 40609.18781624292, 41812.0, 54271.743695378405, 55329.44670553404, 55359.0, 55366.72316638552, 55412.16881746696, 8437.493750374997, 8581.06925941683, 8621.827563448822, 8693.956530217309, 8826.646934122564, 11983.940080299542, 12414.937925310316, 12614.180078294881, 12891.290978996245, 12963.35183240853, 11132.721681958015, 11154.609588664358, 11193.440327983732, 11418.942905285752, 11519.769604607693, 11483.8277425837, 11645.708857278298, 11685.357305348287, 11859.0, 11939.880601194225, 11641.0, 11859.58491452795, 12242.816357754818, 12365.75268494643, 12434.813477798021, 8834.469931804113, 8903.020667726674, 8921.821563568823, 9066.138716821892, 9067.047959964373, 11503.884961150616, 12075.396230188631, 12196.93901530522, 12214.75570488603, 12355.196912200678], "value": 9746.216084865726, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.8141241638587, 1625.7236269834132, 1661.783968084149, 1897.5445892985688, 1971.8915459649722, 9052.0, 9331.300152488566, 9344.159025687695, 9620.951895240514, 9629.711108666734, 9310.86033709495, 9532.332736708433, 9548.904510954888, 9568.76078098048, 9579.425234485936, 2107.968380474294, 2362.7164740231196, 2444.144549407702, 2509.4102885821862, 2536.03630620364, 2495.987520062406, 2518.8362756420865, 2524.507720994407, 2528.190978886757, 2529.53203657324, 2402.9759702403016, 2412.372783076404, 2422.697162854643, 2422.9878850605724, 2423.7333893271725, 2039.479932617188, 2423.5758742220128, 2492.950140997188, 2493.800495960319, 2517.1944977607172, 2386.8925898334614, 2388.7133543974833, 2424.2121310574134, 2425.38152771044, 2427.5873101572774, 9708.223342132613, 9743.707688769347, 9744.707658770178, 9775.169110625606, 9805.607775688964, 5570.108782594765, 5707.3721890592005, 5714.314282286111, 5718.285214348207, 5721.828345149652, 3456.9654303456896, 3555.182308069133, 3586.8744593939346, 3587.4080776671817, 3621.4024685926793, 1111.8499002634703, 1124.7637996020765, 1147.2370873369282, 1151.3494875395374, 1176.7940610393148, 13952.651183720387, 13966.581002569837, 13978.720425591431, 14018.92990535051, 14085.366158522891, 4904.141775189328, 5035.169197082514, 5072.213806859942, 5103.974480127576, 5119.6160287978555, 11484.310941343547, 11564.42177891102, 11572.537098515964, 11579.189456737948, 11581.363025033535, 5040.621953353513, 5610.438956104357, 5656.377798442168, 5660.235868157746, 5670.121131224667, 4960.702357858468, 5050.4949505049935, 5052.898942021139, 5098.796048158107, 5099.745012749348, 4209.2633789086785, 4263.55232700562, 4274.187904298174, 4453.198424283595, 4494.07871386365, 1285.916415432994, 1288.3171918883006, 1290.1356091418772, 1295.3005377096451, 1300.219868079164, 1033.834586466162, 1044.7858189071221, 1046.8900765419673, 1055.6621880998161, 1067.4395942130443, 6497.252815926229, 6708.672721653301, 6728.831779205461, 6758.425533829582, 6797.5581587196675, 466.9579737823612, 470.602341021836, 498.19044053413205, 498.2799854210703, 509.81901424994334, 4100.323446631272, 4119.546849846514, 4152.979235103866, 4182.623563879205, 4258.254805409041, 11827.822582661267, 12229.449674764657, 12326.50693972231, 12345.321007344503, 12346.320952347527, 12060.939695301468, 12179.634610961686, 12199.51201951912, 12200.145989780807, 12273.018158547295, 8623.913760862311, 8956.462612243156, 8975.23710484603, 9165.54172291396, 9172.266218702487, 6083.8174854754425, 6334.208223972004, 6377.681115944091, 6398.648074355769, 6492.35076492347, 10475.633352832614, 10803.729906752395, 10820.242583019113, 10908.509117089592, 10951.616693415692, 10667.146628269875, 10990.395528246023, 11087.113030957666, 11117.666470005914, 11136.053435458076, 7902.920970790218, 8175.918240817516, 8176.264136227768, 8703.347248956481, 8899.243564297107, 9248.722538323862, 9468.573914174016, 9506.382085164441, 9661.468619225729, 9680.080392362714, 8430.241278284984, 8716.128387161487, 8722.25860801839, 8772.0, 8847.557622118997, 8360.540170290693, 8364.0, 8507.447015943944, 8527.147285271423, 8684.78288042804, 7225.747098851515, 7234.131904171533, 7236.4210863131875, 7245.058142441449, 7247.420206383582, 5590.357108932525, 5726.141078838041, 5821.912671310019, 5858.9121163181735, 5875.618084824473, 5662.207290979349, 5713.514351280021, 5739.512141468022, 5812.418758124154, 5866.0], "value": 4910.560580930079, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.58453816765297, 353.681686482166, 356.3870143353433, 360.4053312035143, 363.97634153779984, 5437.755301011454, 5442.265294185284, 5497.93827314757, 5512.641678290918, 5517.337919449672, 5381.784728610861, 5385.407605163438, 5412.2422860799425, 5417.783288668448, 5436.293281873351, 4107.691923105764, 4277.925206274954, 4284.314509678452, 4284.443022407083, 4284.993026638753, 4270.081932384537, 4299.914001719963, 4312.159128969852, 4317.43873296471, 4319.343603683743, 4060.5127384713874, 4097.89755256118, 4276.786160691953, 4290.6138447539715, 4308.784560771965, 4167.4790651168605, 4183.4979802423595, 4185.97907010463, 4192.140611174717, 4192.81132349045, 3681.226942342111, 3686.1521849974656, 3687.4100143976834, 3689.797061161635, 3699.8335074921692, 4992.201247800371, 5052.9242061369105, 5152.613553983429, 5173.508516690946, 5218.947810521884, 4789.377380940456, 4789.802549362659, 4824.324594556765, 4827.841318083635, 4840.685355451885, 5603.635763675389, 5607.467290607386, 5667.773289068432, 5678.290213723285, 5700.429957004308, 3907.179492306619, 4127.8968025799595, 4129.442525259082, 4140.523839758406, 4152.771597562133, 5885.70571471429, 5889.087191485284, 5911.349751527328, 5929.911051334234, 5932.9406705932815, 4386.561343865652, 4389.561043895585, 4393.604575588212, 4399.098184872094, 4399.450068741407, 4250.107477429712, 4399.076193999296, 4412.448443944507, 4417.0, 4417.491988421309, 4908.680935739166, 4918.975405122951, 4924.0, 4929.926051109164, 4937.456879743226, 5610.635308705003, 5619.157126431068, 5711.400302968212, 5750.597458177971, 5769.653820770767, 1445.4651778841685, 1513.8107736532934, 1517.4613012380667, 1518.180182701329, 1522.4595268679682, 966.7583104223944, 990.3909095905966, 991.306085739985, 1002.644061358222, 1003.8343673293971, 4855.757212139379, 4895.192293271571, 4918.975405122951, 4924.9507504924495, 4947.876303092379, 5100.719460429637, 5128.358955130609, 5136.486351364908, 5167.638265321467, 5602.411746766612, 5373.844623405908, 5515.503604675599, 5537.833864984057, 5559.722013899289, 5562.137868630369, 3074.431230222416, 3077.7999430036975, 3092.5361195820806, 3093.381323735217, 3139.4192074466296, 5751.712414379264, 5998.0806142035, 6022.7289771959495, 6023.849403764941, 6029.879402411927, 5936.198613187251, 5939.792107276225, 5943.603253235487, 5947.226860508106, 5978.521718262528, 5891.499222566044, 5958.136070269873, 6029.6683682397015, 6094.0, 6109.0, 5873.676947767743, 5896.970515147397, 5900.173975643499, 5912.911306330322, 5919.845630102089, 5762.596618236851, 5779.248697669276, 5783.0, 5800.332961709458, 5803.216565763568, 5702.458266464845, 5962.52299816005, 5992.1910542078, 6002.909956350745, 6009.549283803644, 4263.507772279678, 4307.483102027777, 4308.052228509723, 4342.761148136883, 4369.890752731207, 5728.3985181557, 5858.576774535199, 5865.648061116347, 5888.939990801697, 5902.321233058082, 5626.212330273846, 5669.121286200645, 5683.715814209356, 5710.714464276686, 5726.599138060463, 5632.127020311858, 5641.294838145232, 5659.9717001416375, 5692.0, 5771.769129234784, 5295.25866378715, 5500.3949565548555, 5562.777488900399, 5672.943270567406, 5703.08750599902, 5685.545156387562, 5724.771009159587, 5773.595848290579, 5778.226451303482, 5786.640139567283, 5373.462653734595, 5495.0, 5608.635438696471, 5614.399896029775, 5640.153976903497], "value": 4345.467792163409, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.6995236881429, 782.6126067596541, 786.1941509952298, 803.7468197517782, 831.7671052105412, 16504.66990660186, 16922.323107075703, 16940.23768930398, 17129.229184686686, 17444.56388590286, 14263.358148883299, 14300.284985750723, 14399.0, 14402.567922962302, 14404.91963102769, 734.5776178697257, 875.7810547363159, 896.708569714842, 901.625825282507, 902.2376092202112, 894.369469523984, 896.4755617963499, 897.5691667999364, 899.1817446124029, 899.8245342158282, 757.8408534207796, 938.9718308450723, 941.3551717073826, 941.4586612697692, 942.2838642631615, 902.9503377314245, 926.8887733471959, 929.4887811703603, 929.6885543342988, 929.9674511392136, 688.4733179117978, 688.6006116452446, 688.9758858439981, 689.1351354050685, 689.2005273882272, 14847.777283340758, 14967.850321496864, 14986.250687465583, 14997.625059373602, 14998.325075371635, 15151.0, 15266.694666106616, 15404.768928466083, 15466.072035677895, 15634.843651563453, 12972.156809807333, 13004.21974681522, 13214.20714757108, 13337.399817008258, 13419.530316438877, 4738.857834264978, 4785.688930219525, 4819.975900120512, 4831.9033619327765, 4915.852524424273, 67041.66479167574, 67620.6618966902, 69245.0, 69511.304886951, 76934.0, 11572.24780389272, 12126.696832579257, 12242.0, 12334.938325308316, 12876.163049401946, 22253.0, 22322.776772232395, 22344.55310893773, 22435.775642243694, 22438.663420048713, 9312.0, 9427.952860235655, 9613.471259080678, 9748.561314740711, 9951.651692190884, 5077.492250774967, 5092.719900405513, 5245.239440281138, 5288.68267903927, 5335.786568537215, 3518.5601799775027, 3680.4479328100997, 3687.2625474905126, 3697.4453831925425, 3698.1494256320657, 622.7446746833867, 630.8454428665007, 723.9276072392719, 731.0350337554484, 732.9120505539263, 730.1420830524113, 739.6671497826, 744.4863044499278, 766.5362455714212, 798.8641930871765, 10833.891661083446, 11040.889591104147, 11115.054865398522, 11323.207375483636, 11413.942930285462, 749.0, 763.4846478626905, 774.8643987302199, 783.7687882074802, 814.6741303478656, 5073.162928116893, 5159.226116082617, 5167.974160129251, 5182.948170518246, 5425.945740542623, 77028.40336050923, 120973.0, 124320.37839810744, 130445.0, 130610.0, 70407.64796175987, 102362.0, 110473.0, 111379.0, 113097.0, 97022.0, 97911.51044244732, 98920.50539747255, 99309.00690992997, 100175.49912250393, 10425.478726063819, 10539.683809485728, 10626.36241825493, 10690.358578485315, 10694.73263168427, 55261.72369138129, 56359.43640563706, 57135.42864571467, 57665.0, 60665.393346065975, 67364.6631766838, 74449.0, 83877.58061209654, 84248.15751842648, 85557.0, 14075.0, 14168.433262669378, 14203.360848762037, 14623.41506339777, 14857.257137143315, 15827.604309892346, 16191.514254572381, 16779.0, 17175.57061073483, 17195.312187512358, 14288.142711437347, 14438.422463101359, 14592.27038648042, 15056.0, 15300.540983770505, 14873.702525949637, 15332.233388330762, 15478.922605387352, 15879.12664803447, 15916.283767230734, 13790.793138102736, 13951.58145255644, 14176.929115354356, 15028.924855375653, 15693.764593530877, 12039.569566172227, 12354.135210535176, 12403.069769767124, 12415.503379865066, 12606.55877044299, 11914.463849126636, 11981.76036479248, 12066.396680166132, 12070.577529786415, 12087.153899227322], "value": 8170.591147936502, "unit": "score"}], "name": "dromaeo_css", "value": 7668.866719017783, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 111.0, 111.0, 111.0, 109.0, 110.0, 110.0, 110.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 129.0, 134.0, 178.0, 130.0, 154.0, 158.0, 124.0, 177.0, 122.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 161.0, 161.0, 161.0, 160.0, 160.0, 162.0, 162.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 116.0, 74.0, 75.0, 74.0, 73.0, 99.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 119.0, 84.0, 83.0, 84.0, 123.0, 83.0, 83.0, 136.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 52.0, 50.0, 50.0, 51.0, 52.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 79.0, 75.0, 81.0, 79.0, 77.0, 76.0, 113.0, 82.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 131.0, 113.0, 116.0, 117.0, 120.0, 118.0], "value": 118.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 153.0, 154.0, 152.0, 151.0, 155.0, 154.0, 149.0, 150.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 63.0, 64.0, 61.0, 61.0, 61.0, 59.0, 61.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1474.2000000000003, "alertThreshold": 2.0}]} 22:50:36 INFO - Return code: 0 22:50:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:50:36 ERROR - # TBPL SUCCESS # 22:50:36 INFO - Running post-action listener: _resource_record_post_action 22:50:36 INFO - ##### 22:50:36 INFO - ##### Finished run-tests step (success) 22:50:36 INFO - ##### 22:50:36 INFO - Running post-run listener: _resource_record_post_run 22:50:36 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 299008; Write bytes: 505581568; Read time: 888; Write time: 562056 22:50:36 INFO - TinderboxPrint: CPU usage
13.1% 22:50:36 INFO - TinderboxPrint: I/O read bytes / time
299,008 / 888 22:50:36 INFO - TinderboxPrint: I/O write bytes / time
505,581,568 / 562,056 22:50:36 INFO - TinderboxPrint: CPU idle
8,253.4 (86.9%) 22:50:36 INFO - TinderboxPrint: CPU user
1,198.2 (12.6%) 22:50:36 INFO - TinderboxPrint: Swap in / out
0 / 0 22:50:36 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 4096; Write bytes: 252379136; Read time: 784; Write time: 408276 22:50:36 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 270336; Write bytes: 252616704; Read time: 96; Write time: 153320 22:50:36 INFO - Running post-run listener: _upload_blobber_files 22:50:36 INFO - Blob upload gear active. 22:50:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:50:36 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 22:50:36 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'] 22:50:36 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 22:50:37 INFO - (blobuploader) - INFO - Open directory for files ... 22:50:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:50:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:50 INFO - (blobuploader) - INFO - Done attempting. 22:50:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:50:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:50 INFO - (blobuploader) - INFO - Done attempting. 22:50:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:50:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:50:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:51 INFO - (blobuploader) - INFO - Done attempting. 22:50:51 INFO - (blobuploader) - INFO - Iteration through files over. 22:50:51 INFO - Return code: 0 22:50:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:50:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:50:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f86c34b544c16423b9eec98f4e1fa16b9f00575d8fc13e87d0fc8938bd67686ab38aeb1253238371da0f3e3cc5992827cb54612c5a3bbc55843ae438f2640bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff92a84e2d36e5162d14885800290fd4b103df524c68c0f626e699fbe9a1ddc1fda7a4d3c17c294363e25eff009b77ce0cc8711eccfaf75f08e31ebd68d2d74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f465857e28d6c07cc1ee58be95f41ab0eb423d3399eeb6a398d3b347a14ff386929a41569f56ce8c4065c8dcdfc9dba17e62d9ab1a55dbefafc19499d127eca"} 22:50:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:50:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:50:51 INFO - Contents: 22:50:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f86c34b544c16423b9eec98f4e1fa16b9f00575d8fc13e87d0fc8938bd67686ab38aeb1253238371da0f3e3cc5992827cb54612c5a3bbc55843ae438f2640bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff92a84e2d36e5162d14885800290fd4b103df524c68c0f626e699fbe9a1ddc1fda7a4d3c17c294363e25eff009b77ce0cc8711eccfaf75f08e31ebd68d2d74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f465857e28d6c07cc1ee58be95f41ab0eb423d3399eeb6a398d3b347a14ff386929a41569f56ce8c4065c8dcdfc9dba17e62d9ab1a55dbefafc19499d127eca"} 22:50:51 INFO - Running post-run listener: copy_logs_to_upload_dir 22:50:51 INFO - Copying logs to upload dir... 22:50:51 INFO - mkdir: /builds/slave/test/build/upload/logs 22:50:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.601790 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-27 22:50:51.598156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:51.601770) ========= 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=db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237 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/3f86c34b544c16423b9eec98f4e1fa16b9f00575d8fc13e87d0fc8938bd67686ab38aeb1253238371da0f3e3cc5992827cb54612c5a3bbc55843ae438f2640bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff92a84e2d36e5162d14885800290fd4b103df524c68c0f626e699fbe9a1ddc1fda7a4d3c17c294363e25eff009b77ce0cc8711eccfaf75f08e31ebd68d2d74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f465857e28d6c07cc1ee58be95f41ab0eb423d3399eeb6a398d3b347a14ff386929a41569f56ce8c4065c8dcdfc9dba17e62d9ab1a55dbefafc19499d127eca"} build_url:https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/aywU8DYvRxqd8iA4u1-NPA/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/3f86c34b544c16423b9eec98f4e1fa16b9f00575d8fc13e87d0fc8938bd67686ab38aeb1253238371da0f3e3cc5992827cb54612c5a3bbc55843ae438f2640bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff92a84e2d36e5162d14885800290fd4b103df524c68c0f626e699fbe9a1ddc1fda7a4d3c17c294363e25eff009b77ce0cc8711eccfaf75f08e31ebd68d2d74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f465857e28d6c07cc1ee58be95f41ab0eb423d3399eeb6a398d3b347a14ff386929a41569f56ce8c4065c8dcdfc9dba17e62d9ab1a55dbefafc19499d127eca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:51.628540) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:51.629128) ========= 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=db0483c52d987ecb441a263b000001ad-1469682342.572404-970412237 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004385 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:50:51.715214) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 22:50:51.715619) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 22:50:51.715961) ========= ========= Total master_lag: 0.33 =========