builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1469114686.3 results: success (0) buildid: 20160721053620 builduid: ffbb0662f45c4231825261b836b0a177 revision: 2a0945d032b12dd57f8e599b35a4d76f3557350e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.300115) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.300570) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.300839) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.315987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.316360) ========= 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=af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 08:24:46-- 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% 21.6M=0.001s 2016-07-21 08:24:46 (21.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116996 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.446801) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.447106) ========= 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=af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014158 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.496089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:46.496497) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2a0945d032b12dd57f8e599b35a4d76f3557350e --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 2a0945d032b12dd57f8e599b35a4d76f3557350e --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=af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 08:24:46,871 truncating revision to first 12 chars 2016-07-21 08:24:46,871 Setting DEBUG logging. 2016-07-21 08:24:46,871 attempt 1/10 2016-07-21 08:24:46,871 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2a0945d032b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 08:24:47,186 unpacking tar archive at: mozilla-aurora-2a0945d032b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.660252 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:47.171149) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:47.171467) ========= script_repo_revision: 2a0945d032b12dd57f8e599b35a4d76f3557350e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:47.171877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:47.172136) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 08:24:47.181105) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-21 08:24:47.181375) ========= /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=af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:24:47 INFO - MultiFileLogger online at 20160721 08:24:47 in /builds/slave/test 08:24:47 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 08:24:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:24:47 INFO - {'append_to_log': False, 08:24:47 INFO - 'base_work_dir': '/builds/slave/test', 08:24:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:24:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:24:47 INFO - 'branch': 'Mozilla-Aurora', 08:24:47 INFO - 'buildbot_json_path': 'buildprops.json', 08:24:47 INFO - 'config_files': ('talos/linux_config.py',), 08:24:47 INFO - 'default_actions': ('clobber', 08:24:47 INFO - 'read-buildbot-config', 08:24:47 INFO - 'download-and-extract', 08:24:47 INFO - 'populate-webroot', 08:24:47 INFO - 'create-virtualenv', 08:24:47 INFO - 'install', 08:24:47 INFO - 'run-tests'), 08:24:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:24:47 INFO - 'download_minidump_stackwalk': True, 08:24:47 INFO - 'download_symbols': 'ondemand', 08:24:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:24:47 INFO - 'tooltool.py': '/tools/tooltool.py', 08:24:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:24:47 INFO - '/tools/misc-python/virtualenv.py')}, 08:24:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:24:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:24:47 INFO - 'installer_path': 'installer.exe', 08:24:47 INFO - 'log_level': 'info', 08:24:47 INFO - 'log_name': 'talos', 08:24:47 INFO - 'log_to_console': True, 08:24:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:24:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:24:47 INFO - 'opt_config_files': (), 08:24:47 INFO - 'pip_index': False, 08:24:47 INFO - 'sps_profile': False, 08:24:47 INFO - 'sps_profile_interval': 0, 08:24:47 INFO - 'suite': 'dromaeojs', 08:24:47 INFO - 'system_bits': '32', 08:24:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:24:47 INFO - 'title': 'talos-linux64-ix-020', 08:24:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:24:47 INFO - 'use_talos_json': True, 08:24:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:24:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:24:47 INFO - 'work_dir': 'build'} 08:24:47 INFO - ##### 08:24:47 INFO - ##### Running clobber step. 08:24:47 INFO - ##### 08:24:47 INFO - Running pre-action listener: _resource_record_pre_action 08:24:47 INFO - Running main action method: clobber 08:24:47 INFO - rmtree: /builds/slave/test/build 08:24:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:24:48 INFO - Running post-action listener: _resource_record_post_action 08:24:48 INFO - ##### 08:24:48 INFO - ##### Finished clobber step (success) 08:24:48 INFO - ##### 08:24:48 INFO - ##### 08:24:48 INFO - ##### Running read-buildbot-config step. 08:24:48 INFO - ##### 08:24:48 INFO - Running pre-action listener: _resource_record_pre_action 08:24:48 INFO - Running main action method: read_buildbot_config 08:24:48 INFO - Using buildbot properties: 08:24:48 INFO - { 08:24:48 INFO - "project": "", 08:24:48 INFO - "product": "firefox", 08:24:48 INFO - "script_repo_revision": "production", 08:24:48 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:24:48 INFO - "repository": "", 08:24:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:24:48 INFO - "buildid": "20160721053620", 08:24:48 INFO - "pgo_build": "True", 08:24:48 INFO - "basedir": "/builds/slave/test", 08:24:48 INFO - "buildnumber": 376, 08:24:48 INFO - "slavename": "talos-linux64-ix-020", 08:24:48 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:24:48 INFO - "platform": "ubuntu64_hw", 08:24:48 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:24:48 INFO - "branch": "mozilla-aurora", 08:24:48 INFO - "repo_path": "releases/mozilla-aurora", 08:24:48 INFO - "revision": "2a0945d032b12dd57f8e599b35a4d76f3557350e", 08:24:48 INFO - "stage_platform": "linux64-pgo", 08:24:48 INFO - "builduid": "ffbb0662f45c4231825261b836b0a177", 08:24:48 INFO - "slavebuilddir": "test" 08:24:48 INFO - } 08:24:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 08:24:48 INFO - Running post-action listener: _resource_record_post_action 08:24:48 INFO - ##### 08:24:48 INFO - ##### Finished read-buildbot-config step (success) 08:24:48 INFO - ##### 08:24:48 INFO - ##### 08:24:48 INFO - ##### Running download-and-extract step. 08:24:48 INFO - ##### 08:24:48 INFO - Running pre-action listener: _resource_record_pre_action 08:24:48 INFO - Running main action method: download_and_extract 08:24:48 INFO - mkdir: /builds/slave/test/build/tests 08:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:48 INFO - https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:24:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:24:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:24:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:50 INFO - Downloaded 1361 bytes. 08:24:50 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:24:50 INFO - Using the following test package requirements: 08:24:50 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:24:50 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:24:50 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:24:50 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'jsshell-linux-x86_64.zip'], 08:24:50 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:24:50 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:24:50 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:24:50 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:24:50 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:24:50 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:24:50 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:24:50 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:50 INFO - https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:24:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:24:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:24:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:51 INFO - Downloaded 24489879 bytes. 08:24:51 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'] 08:24:51 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 08:24:52 INFO - Return code: 0 08:24:52 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 08:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:52 INFO - https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:24:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:24:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:24:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:53 INFO - Downloaded 24489879 bytes. 08:24:53 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'] 08:24:53 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 08:24:55 INFO - Return code: 0 08:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:55 INFO - https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:24:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:24:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:24:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:55 INFO - Downloaded 10833369 bytes. 08:24:55 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'] 08:24:55 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 08:24:56 INFO - Return code: 0 08:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:56 INFO - https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:24:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:24:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:24:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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 08:24:57 INFO - Downloaded 63638687 bytes. 08:24:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:24:57 INFO - mkdir: /builds/slave/test/properties 08:24:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:24:57 INFO - Writing to file /builds/slave/test/properties/build_url 08:24:57 INFO - Contents: 08:24:57 INFO - build_url:https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:24:58 INFO - Running post-action listener: _resource_record_post_action 08:24:58 INFO - Running post-action listener: set_extra_try_arguments 08:24:58 INFO - ##### 08:24:58 INFO - ##### Finished download-and-extract step (success) 08:24:58 INFO - ##### 08:24:58 INFO - ##### 08:24:58 INFO - ##### Running populate-webroot step. 08:24:58 INFO - ##### 08:24:58 INFO - Running pre-action listener: _resource_record_pre_action 08:24:58 INFO - Running main action method: populate_webroot 08:24:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:24:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:24:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:24:58 INFO - '--tppagecycles', 08:24:58 INFO - '7'], 08:24:58 INFO - 'tests': ['tsvgm']}}, 08:24:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:24:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:24:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:24:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:24:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['damp', 'tps']}, 08:24:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'tests': ['damp', 'tps']}, 08:24:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['dromaeo_dom']}, 08:24:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:24:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other-e10s': {'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['a11yr', 08:24:58 INFO - 'ts_paint', 08:24:58 INFO - 'tpaint', 08:24:58 INFO - 'sessionrestore', 08:24:58 INFO - 'sessionrestore_no_auto_restore', 08:24:58 INFO - 'tabpaint']}, 08:24:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tsvgx', 08:24:58 INFO - 'tsvgr_opacity', 08:24:58 INFO - 'tart', 08:24:58 INFO - 'tscrollx', 08:24:58 INFO - 'cart']}, 08:24:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:24:58 INFO - 'tsvgr_opacity', 08:24:58 INFO - 'tart', 08:24:58 INFO - 'tscrollx', 08:24:58 INFO - 'cart']}, 08:24:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s'], 08:24:58 INFO - 'tests': ['tp5o']}, 08:24:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'tests': ['tp5o']}, 08:24:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'talos_options': ['--disable-e10s', 08:24:58 INFO - '--xperf_path', 08:24:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:58 INFO - 'tests': ['tp5n']}, 08:24:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:58 INFO - 'talos_options': ['--xperf_path', 08:24:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:58 INFO - 'tests': ['tp5n']}}, 08:24:58 INFO - 'talos.zip': {'path': '', 08:24:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:24:58 INFO - Running post-action listener: _resource_record_post_action 08:24:58 INFO - ##### 08:24:58 INFO - ##### Finished populate-webroot step (success) 08:24:58 INFO - ##### 08:24:58 INFO - ##### 08:24:58 INFO - ##### Running create-virtualenv step. 08:24:58 INFO - ##### 08:24:58 INFO - Running pre-action listener: _resource_record_pre_action 08:24:58 INFO - Running main action method: create_virtualenv 08:24:58 INFO - Creating virtualenv /builds/slave/test/build/venv 08:24:58 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 08:24:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:24:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:24:58 INFO - Using real prefix '/usr' 08:24:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:24:58 INFO - Installing distribute.............................................................................................................................................................................................done. 08:25:00 INFO - Installing pip.................done. 08:25:00 INFO - Return code: 0 08:25:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:25:00 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')]} 08:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:00 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')]} 08:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:00 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:00 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 08:25:00 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 08:25:00 INFO - Using env: {'DISPLAY': ':0', 08:25:00 INFO - 'HOME': '/home/cltbld', 08:25:00 INFO - 'LANG': 'en_US.UTF-8', 08:25:00 INFO - 'LANGUAGE': 'en_US:en', 08:25:00 INFO - 'LOGNAME': 'cltbld', 08:25:00 INFO - 'MAIL': '/var/mail/cltbld', 08:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:00 INFO - 'MOZ_NO_REMOTE': '1', 08:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:00 INFO - 'NO_EM_RESTART': '1', 08:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:00 INFO - 'PWD': '/builds/slave/test', 08:25:00 INFO - 'SHELL': '/bin/bash', 08:25:00 INFO - 'SHLVL': '1', 08:25:00 INFO - 'TERM': 'linux', 08:25:00 INFO - 'TMOUT': '86400', 08:25:00 INFO - 'USER': 'cltbld', 08:25:00 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:00 INFO - '_': '/tools/buildbot/bin/python'} 08:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:00 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 08:25:00 INFO - Cleaning up... 08:25:00 INFO - Return code: 0 08:25:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:25:00 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')]} 08:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:00 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')]} 08:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:00 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:00 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 08:25:00 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 08:25:00 INFO - Using env: {'DISPLAY': ':0', 08:25:00 INFO - 'HOME': '/home/cltbld', 08:25:00 INFO - 'LANG': 'en_US.UTF-8', 08:25:00 INFO - 'LANGUAGE': 'en_US:en', 08:25:00 INFO - 'LOGNAME': 'cltbld', 08:25:00 INFO - 'MAIL': '/var/mail/cltbld', 08:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:00 INFO - 'MOZ_NO_REMOTE': '1', 08:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:00 INFO - 'NO_EM_RESTART': '1', 08:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:00 INFO - 'PWD': '/builds/slave/test', 08:25:00 INFO - 'SHELL': '/bin/bash', 08:25:00 INFO - 'SHLVL': '1', 08:25:00 INFO - 'TERM': 'linux', 08:25:00 INFO - 'TMOUT': '86400', 08:25:00 INFO - 'USER': 'cltbld', 08:25:00 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:00 INFO - '_': '/tools/buildbot/bin/python'} 08:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:01 INFO - Downloading/unpacking psutil>=3.1.1 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:25:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:03 INFO - Installing collected packages: psutil 08:25:03 INFO - Running setup.py install for psutil 08:25:03 INFO - building 'psutil._psutil_linux' extension 08:25:03 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 08:25:03 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 08:25:03 INFO - building 'psutil._psutil_posix' extension 08:25:03 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 08:25:03 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 08:25:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:03 INFO - Successfully installed psutil 08:25:03 INFO - Cleaning up... 08:25:03 INFO - Return code: 0 08:25:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:25:03 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')]} 08:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:03 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')]} 08:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:03 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:03 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 08:25:03 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 08:25:03 INFO - Using env: {'DISPLAY': ':0', 08:25:03 INFO - 'HOME': '/home/cltbld', 08:25:03 INFO - 'LANG': 'en_US.UTF-8', 08:25:03 INFO - 'LANGUAGE': 'en_US:en', 08:25:03 INFO - 'LOGNAME': 'cltbld', 08:25:03 INFO - 'MAIL': '/var/mail/cltbld', 08:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:03 INFO - 'MOZ_NO_REMOTE': '1', 08:25:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:03 INFO - 'NO_EM_RESTART': '1', 08:25:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:03 INFO - 'PWD': '/builds/slave/test', 08:25:03 INFO - 'SHELL': '/bin/bash', 08:25:03 INFO - 'SHLVL': '1', 08:25:03 INFO - 'TERM': 'linux', 08:25:03 INFO - 'TMOUT': '86400', 08:25:03 INFO - 'USER': 'cltbld', 08:25:03 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:03 INFO - '_': '/tools/buildbot/bin/python'} 08:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:25:05 INFO - Installing collected packages: mozsystemmonitor 08:25:05 INFO - Running setup.py install for mozsystemmonitor 08:25:05 INFO - Successfully installed mozsystemmonitor 08:25:05 INFO - Cleaning up... 08:25:06 INFO - Return code: 0 08:25:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:25:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:25:06 INFO - Using env: {'DISPLAY': ':0', 08:25:06 INFO - 'HOME': '/home/cltbld', 08:25:06 INFO - 'LANG': 'en_US.UTF-8', 08:25:06 INFO - 'LANGUAGE': 'en_US:en', 08:25:06 INFO - 'LOGNAME': 'cltbld', 08:25:06 INFO - 'MAIL': '/var/mail/cltbld', 08:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:06 INFO - 'MOZ_NO_REMOTE': '1', 08:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:06 INFO - 'NO_EM_RESTART': '1', 08:25:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:06 INFO - 'PWD': '/builds/slave/test', 08:25:06 INFO - 'SHELL': '/bin/bash', 08:25:06 INFO - 'SHLVL': '1', 08:25:06 INFO - 'TERM': 'linux', 08:25:06 INFO - 'TMOUT': '86400', 08:25:06 INFO - 'USER': 'cltbld', 08:25:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:06 INFO - '_': '/tools/buildbot/bin/python'} 08:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:06 INFO - Downloading/unpacking jsonschema==2.5.1 08:25:06 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 08:25:06 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 08:25:06 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 08:25:06 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 08:25:06 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 08:25:06 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 08:25:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:25:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:25:08 INFO - warning: no files found matching '*.txt' 08:25:08 INFO - no previously-included directories found matching 'build' 08:25:08 INFO - no previously-included directories found matching 'dist' 08:25:08 INFO - no previously-included directories found matching '.git*' 08:25:08 INFO - Installing collected packages: jsonschema, functools32 08:25:08 INFO - Running setup.py install for functools32 08:25:08 INFO - warning: no files found matching '*.txt' 08:25:08 INFO - no previously-included directories found matching 'build' 08:25:08 INFO - no previously-included directories found matching 'dist' 08:25:08 INFO - no previously-included directories found matching '.git*' 08:25:08 INFO - Successfully installed jsonschema functools32 08:25:08 INFO - Cleaning up... 08:25:08 INFO - Return code: 0 08:25:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:08 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:08 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 08:25:08 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 08:25:08 INFO - Using env: {'DISPLAY': ':0', 08:25:08 INFO - 'HOME': '/home/cltbld', 08:25:08 INFO - 'LANG': 'en_US.UTF-8', 08:25:08 INFO - 'LANGUAGE': 'en_US:en', 08:25:08 INFO - 'LOGNAME': 'cltbld', 08:25:08 INFO - 'MAIL': '/var/mail/cltbld', 08:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:08 INFO - 'MOZ_NO_REMOTE': '1', 08:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:08 INFO - 'NO_EM_RESTART': '1', 08:25:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:08 INFO - 'PWD': '/builds/slave/test', 08:25:08 INFO - 'SHELL': '/bin/bash', 08:25:08 INFO - 'SHLVL': '1', 08:25:08 INFO - 'TERM': 'linux', 08:25:08 INFO - 'TMOUT': '86400', 08:25:08 INFO - 'USER': 'cltbld', 08:25:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:08 INFO - '_': '/tools/buildbot/bin/python'} 08:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:08 INFO - Downloading/unpacking blobuploader==1.2.4 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:25:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:25:10 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 08:25:10 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 08:25:10 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 08:25:10 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 08:25:10 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 08:25:10 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 08:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:25:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:25:11 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 08:25:11 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 08:25:11 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 08:25:11 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 08:25:11 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 08:25:11 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 08:25:11 INFO - Downloading docopt-0.6.1.tar.gz 08:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:25:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:25:11 INFO - Running setup.py install for blobuploader 08:25:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:25:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:25:11 INFO - Running setup.py install for requests 08:25:11 INFO - Running setup.py install for docopt 08:25:12 INFO - Successfully installed blobuploader requests docopt 08:25:12 INFO - Cleaning up... 08:25:12 INFO - Return code: 0 08:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:12 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:12 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 08:25:12 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 08:25:12 INFO - Using env: {'DISPLAY': ':0', 08:25:12 INFO - 'HOME': '/home/cltbld', 08:25:12 INFO - 'LANG': 'en_US.UTF-8', 08:25:12 INFO - 'LANGUAGE': 'en_US:en', 08:25:12 INFO - 'LOGNAME': 'cltbld', 08:25:12 INFO - 'MAIL': '/var/mail/cltbld', 08:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:12 INFO - 'MOZ_NO_REMOTE': '1', 08:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:12 INFO - 'NO_EM_RESTART': '1', 08:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:12 INFO - 'PWD': '/builds/slave/test', 08:25:12 INFO - 'SHELL': '/bin/bash', 08:25:12 INFO - 'SHLVL': '1', 08:25:12 INFO - 'TERM': 'linux', 08:25:12 INFO - 'TMOUT': '86400', 08:25:12 INFO - 'USER': 'cltbld', 08:25:12 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:12 INFO - '_': '/tools/buildbot/bin/python'} 08:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:12 INFO - Running setup.py (path:/tmp/pip-VuT_Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:12 INFO - Running setup.py (path:/tmp/pip-iY9WFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:12 INFO - Running setup.py (path:/tmp/pip-t989lG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:12 INFO - Running setup.py (path:/tmp/pip-WjPJBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:12 INFO - Running setup.py (path:/tmp/pip-G7VKnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:12 INFO - Running setup.py (path:/tmp/pip-bublHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:12 INFO - Running setup.py (path:/tmp/pip-u0BKM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:12 INFO - Running setup.py (path:/tmp/pip-zqqTdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:12 INFO - Running setup.py (path:/tmp/pip-D0bXC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:12 INFO - Running setup.py (path:/tmp/pip-upUNbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:13 INFO - Running setup.py (path:/tmp/pip-9x0YSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:13 INFO - Running setup.py (path:/tmp/pip-3gaF0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:13 INFO - Running setup.py (path:/tmp/pip-WDhjQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:13 INFO - Running setup.py (path:/tmp/pip-0zYRxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:13 INFO - Running setup.py (path:/tmp/pip-4i8MgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:13 INFO - Running setup.py (path:/tmp/pip-hiZW2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:13 INFO - Running setup.py (path:/tmp/pip-moK3pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:25:13 INFO - Running setup.py install for manifestparser 08:25:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:25:13 INFO - Running setup.py install for mozcrash 08:25:13 INFO - Running setup.py install for mozdebug 08:25:13 INFO - Running setup.py install for mozdevice 08:25:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:25:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:25:13 INFO - Running setup.py install for mozfile 08:25:14 INFO - Running setup.py install for mozhttpd 08:25:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Running setup.py install for mozinfo 08:25:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Running setup.py install for mozInstall 08:25:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Running setup.py install for mozleak 08:25:14 INFO - Running setup.py install for mozlog 08:25:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Running setup.py install for moznetwork 08:25:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Running setup.py install for mozprocess 08:25:14 INFO - Running setup.py install for mozprofile 08:25:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:25:14 INFO - Running setup.py install for mozrunner 08:25:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:25:15 INFO - Running setup.py install for mozscreenshot 08:25:15 INFO - Running setup.py install for moztest 08:25:15 INFO - Running setup.py install for mozversion 08:25:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:25:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:25:15 INFO - Cleaning up... 08:25:15 INFO - Return code: 0 08:25:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:15 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:15 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 08:25:15 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 08:25:15 INFO - Using env: {'DISPLAY': ':0', 08:25:15 INFO - 'HOME': '/home/cltbld', 08:25:15 INFO - 'LANG': 'en_US.UTF-8', 08:25:15 INFO - 'LANGUAGE': 'en_US:en', 08:25:15 INFO - 'LOGNAME': 'cltbld', 08:25:15 INFO - 'MAIL': '/var/mail/cltbld', 08:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:15 INFO - 'MOZ_NO_REMOTE': '1', 08:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:15 INFO - 'NO_EM_RESTART': '1', 08:25:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:15 INFO - 'PWD': '/builds/slave/test', 08:25:15 INFO - 'SHELL': '/bin/bash', 08:25:15 INFO - 'SHLVL': '1', 08:25:15 INFO - 'TERM': 'linux', 08:25:15 INFO - 'TMOUT': '86400', 08:25:15 INFO - 'USER': 'cltbld', 08:25:15 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:15 INFO - '_': '/tools/buildbot/bin/python'} 08:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:15 INFO - Running setup.py (path:/tmp/pip-CjSPT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:15 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)) 08:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:15 INFO - Running setup.py (path:/tmp/pip-dv3TUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:15 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)) 08:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:15 INFO - Running setup.py (path:/tmp/pip-UGeW1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:15 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)) 08:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:15 INFO - Running setup.py (path:/tmp/pip-RJANZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:15 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)) 08:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:15 INFO - Running setup.py (path:/tmp/pip-ONXqia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:15 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)) 08:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:15 INFO - Running setup.py (path:/tmp/pip-d5QDvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:16 INFO - Running setup.py (path:/tmp/pip-c6T9UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:16 INFO - Running setup.py (path:/tmp/pip-iKEllB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:16 INFO - Running setup.py (path:/tmp/pip-qxbl8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:16 INFO - Running setup.py (path:/tmp/pip-r8QhQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:16 INFO - Running setup.py (path:/tmp/pip-Dhe2L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:16 INFO - Running setup.py (path:/tmp/pip-9J0RZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:16 INFO - Running setup.py (path:/tmp/pip-mzrXaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:16 INFO - Running setup.py (path:/tmp/pip-9TeaNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:16 INFO - Running setup.py (path:/tmp/pip-7xiaK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:16 INFO - Running setup.py (path:/tmp/pip-kRzA9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:16 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)) 08:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:16 INFO - Running setup.py (path:/tmp/pip-yJmDb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:16 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)) 08:25:16 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)) 08:25:16 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)) 08:25:16 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)) 08:25:16 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)) 08:25:16 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)) 08:25:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:25:16 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 08:25:16 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 08:25:16 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 08:25:16 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 08:25:16 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 08:25:16 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 08:25:18 INFO - Downloading blessings-1.6.tar.gz 08:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:25:18 INFO - Installing collected packages: blessings 08:25:18 INFO - Running setup.py install for blessings 08:25:18 INFO - Successfully installed blessings 08:25:18 INFO - Cleaning up... 08:25:18 INFO - Return code: 0 08:25:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:25:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:19 INFO - Reading from file tmpfile_stdout 08:25:19 INFO - Current package versions: 08:25:19 INFO - argparse == 1.2.1 08:25:19 INFO - blessings == 1.6 08:25:19 INFO - blobuploader == 1.2.4 08:25:19 INFO - docopt == 0.6.1 08:25:19 INFO - functools32 == 3.2.3-2 08:25:19 INFO - jsonschema == 2.5.1 08:25:19 INFO - manifestparser == 1.1 08:25:19 INFO - mozInstall == 1.12 08:25:19 INFO - mozcrash == 0.17 08:25:19 INFO - mozdebug == 0.1 08:25:19 INFO - mozdevice == 0.48 08:25:19 INFO - mozfile == 1.2 08:25:19 INFO - mozhttpd == 0.7 08:25:19 INFO - mozinfo == 0.9 08:25:19 INFO - mozleak == 0.1 08:25:19 INFO - mozlog == 3.2 08:25:19 INFO - moznetwork == 0.27 08:25:19 INFO - mozprocess == 0.23 08:25:19 INFO - mozprofile == 0.28 08:25:19 INFO - mozrunner == 6.12 08:25:19 INFO - mozscreenshot == 0.1 08:25:19 INFO - mozsystemmonitor == 0.3 08:25:19 INFO - moztest == 0.7 08:25:19 INFO - mozversion == 1.4 08:25:19 INFO - psutil == 3.1.1 08:25:19 INFO - requests == 1.2.3 08:25:19 INFO - wsgiref == 0.1.2 08:25:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:19 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:19 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 08:25:19 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 08:25:19 INFO - Using env: {'DISPLAY': ':0', 08:25:19 INFO - 'HOME': '/home/cltbld', 08:25:19 INFO - 'LANG': 'en_US.UTF-8', 08:25:19 INFO - 'LANGUAGE': 'en_US:en', 08:25:19 INFO - 'LOGNAME': 'cltbld', 08:25:19 INFO - 'MAIL': '/var/mail/cltbld', 08:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:19 INFO - 'MOZ_NO_REMOTE': '1', 08:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:19 INFO - 'NO_EM_RESTART': '1', 08:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:19 INFO - 'PWD': '/builds/slave/test', 08:25:19 INFO - 'SHELL': '/bin/bash', 08:25:19 INFO - 'SHLVL': '1', 08:25:19 INFO - 'TERM': 'linux', 08:25:19 INFO - 'TMOUT': '86400', 08:25:19 INFO - 'USER': 'cltbld', 08:25:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:19 INFO - '_': '/tools/buildbot/bin/python'} 08:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 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)) 08:25:19 INFO - Cleaning up... 08:25:19 INFO - Return code: 0 08:25:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:19 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:19 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 08:25:19 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 08:25:19 INFO - Using env: {'DISPLAY': ':0', 08:25:19 INFO - 'HOME': '/home/cltbld', 08:25:19 INFO - 'LANG': 'en_US.UTF-8', 08:25:19 INFO - 'LANGUAGE': 'en_US:en', 08:25:19 INFO - 'LOGNAME': 'cltbld', 08:25:19 INFO - 'MAIL': '/var/mail/cltbld', 08:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:19 INFO - 'MOZ_NO_REMOTE': '1', 08:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:19 INFO - 'NO_EM_RESTART': '1', 08:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:19 INFO - 'PWD': '/builds/slave/test', 08:25:19 INFO - 'SHELL': '/bin/bash', 08:25:19 INFO - 'SHLVL': '1', 08:25:19 INFO - 'TERM': 'linux', 08:25:19 INFO - 'TMOUT': '86400', 08:25:19 INFO - 'USER': 'cltbld', 08:25:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:19 INFO - '_': '/tools/buildbot/bin/python'} 08:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:25:19 INFO - Cleaning up... 08:25:19 INFO - Return code: 0 08:25:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:19 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 0x7fd1ad761f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1211350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, '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': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', '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 08:25:19 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 08:25:19 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 08:25:19 INFO - Using env: {'DISPLAY': ':0', 08:25:19 INFO - 'HOME': '/home/cltbld', 08:25:19 INFO - 'LANG': 'en_US.UTF-8', 08:25:19 INFO - 'LANGUAGE': 'en_US:en', 08:25:19 INFO - 'LOGNAME': 'cltbld', 08:25:19 INFO - 'MAIL': '/var/mail/cltbld', 08:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:19 INFO - 'MOZ_NO_REMOTE': '1', 08:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:19 INFO - 'NO_EM_RESTART': '1', 08:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:19 INFO - 'PWD': '/builds/slave/test', 08:25:19 INFO - 'SHELL': '/bin/bash', 08:25:19 INFO - 'SHLVL': '1', 08:25:19 INFO - 'TERM': 'linux', 08:25:19 INFO - 'TMOUT': '86400', 08:25:19 INFO - 'USER': 'cltbld', 08:25:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:19 INFO - '_': '/tools/buildbot/bin/python'} 08:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:19 INFO - Downloading/unpacking flake8 08:25:19 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 08:25:19 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 08:25:19 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 08:25:19 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 08:25:19 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 08:25:19 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 08:25:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:25:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:25:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:21 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 08:25:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 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 08:25:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:25:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:25:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:25:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:25:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:25:22 INFO - no previously-included directories found matching 'docs/_build' 08:25:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:25:22 INFO - Running setup.py install for pep8 08:25:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:25:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:25:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:25:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:25:22 INFO - no previously-included directories found matching 'docs/_build' 08:25:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:25:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:25:22 INFO - Cleaning up... 08:25:22 INFO - Return code: 0 08:25:22 INFO - Running post-action listener: _resource_record_post_action 08:25:22 INFO - Running post-action listener: _start_resource_monitoring 08:25:22 INFO - Starting resource monitoring. 08:25:22 INFO - ##### 08:25:22 INFO - ##### Finished create-virtualenv step (success) 08:25:22 INFO - ##### 08:25:22 INFO - ##### 08:25:22 INFO - ##### Running install step. 08:25:22 INFO - ##### 08:25:22 INFO - Running pre-action listener: _resource_record_pre_action 08:25:22 INFO - Running main action method: install 08:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:22 INFO - Reading from file tmpfile_stdout 08:25:22 INFO - Detecting whether we're running mozinstall >=1.0... 08:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:25:23 INFO - Reading from file tmpfile_stdout 08:25:23 INFO - Output received: 08:25:23 INFO - Usage: mozinstall [options] installer 08:25:23 INFO - Options: 08:25:23 INFO - -h, --help show this help message and exit 08:25:23 INFO - -d DEST, --destination=DEST 08:25:23 INFO - Directory to install application into. [default: 08:25:23 INFO - "/builds/slave/test"] 08:25:23 INFO - --app=APP Application being installed. [default: firefox] 08:25:23 INFO - mkdir: /builds/slave/test/build/application 08:25:23 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'] 08:25:23 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 08:25:39 INFO - Reading from file tmpfile_stdout 08:25:39 INFO - Output received: 08:25:39 INFO - /builds/slave/test/build/application/firefox/firefox 08:25:39 INFO - Running post-action listener: _resource_record_post_action 08:25:39 INFO - ##### 08:25:39 INFO - ##### Finished install step (success) 08:25:39 INFO - ##### 08:25:39 INFO - ##### 08:25:39 INFO - ##### Running run-tests step. 08:25:39 INFO - ##### 08:25:39 INFO - Running pre-action listener: _resource_record_pre_action 08:25:39 INFO - Running main action method: run_tests 08:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:25:39 INFO - Python 2.7.3 08:25:39 INFO - Return code: 0 08:25:39 INFO - grabbing minidump binary from tooltool 08:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:39 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 0x12a01c0>, '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 0x12a10c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12a1550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:25:39 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 08:25:39 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 08:25:39 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 08:25:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:25:39 INFO - Return code: 0 08:25:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:25:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:25:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:25:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:25:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:25:40 INFO - Return code: 0 08:25:40 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/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 08:25:40 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/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 08:25:40 INFO - Using env: {'DISPLAY': ':0', 08:25:40 INFO - 'HOME': '/home/cltbld', 08:25:40 INFO - 'LANG': 'en_US.UTF-8', 08:25:40 INFO - 'LANGUAGE': 'en_US:en', 08:25:40 INFO - 'LOGNAME': 'cltbld', 08:25:40 INFO - 'MAIL': '/var/mail/cltbld', 08:25:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:40 INFO - 'MOZ_NO_REMOTE': '1', 08:25:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:40 INFO - 'NO_EM_RESTART': '1', 08:25:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:40 INFO - 'PWD': '/builds/slave/test', 08:25:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:40 INFO - 'SHELL': '/bin/bash', 08:25:40 INFO - 'SHLVL': '1', 08:25:40 INFO - 'TERM': 'linux', 08:25:40 INFO - 'TMOUT': '86400', 08:25:40 INFO - 'USER': 'cltbld', 08:25:40 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139', 08:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:40 INFO - '_': '/tools/buildbot/bin/python'} 08:25:40 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/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 08:25:40 INFO - mozversion application_buildid: 20160721053620 08:25:40 INFO - mozversion application_changeset: 2a0945d032b12dd57f8e599b35a4d76f3557350e 08:25:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:25:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:25:40 INFO - mozversion application_name: Firefox 08:25:40 INFO - mozversion application_remotingname: firefox-dev 08:25:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:25:40 INFO - mozversion application_vendor: Mozilla 08:25:40 INFO - mozversion application_version: 49.0a2 08:25:40 INFO - mozversion platform_buildid: 20160721053620 08:25:40 INFO - mozversion platform_changeset: 2a0945d032b12dd57f8e599b35a4d76f3557350e 08:25:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:25:40 INFO - mozversion platform_version: 49.0a2 08:25:40 INFO - using testdate: 1469114740 08:25:40 INFO - actual date: 1469114740 08:25:40 INFO - starting webserver on 'localhost:59141' 08:25:40 INFO - SUITE-START | Running 2 tests 08:25:40 INFO - TEST-START | dromaeo_css 08:25:40 INFO - operating with platform_type : linux_ 08:25:40 INFO - Initialising browser for dromaeo_css test... 08:25:40 INFO - TEST-INFO | started process 20115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDy5Oqr/profile http://localhost:59141/getInfo.html) 08:25:45 INFO - PROCESS | 20115 | __metrics Screen width/height:1600/1200 08:25:45 INFO - PROCESS | 20115 | colorDepth:24 08:25:45 INFO - PROCESS | 20115 | Browser inner width/height: 1024/697 08:25:45 INFO - PROCESS | 20115 | __metrics 08:25:46 INFO - PROCESS | 20115 | 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" 08:25:46 ERROR - PROCESS | 20115 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:25:47 INFO - TEST-INFO | 20115: exit 0 08:25:47 INFO - Browser initialized. 08:25:47 INFO - Running cycle 1/1 for dromaeo_css test... 08:25:47 INFO - TEST-INFO | started process 20205 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDy5Oqr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:25:59 INFO - PROCESS | 20205 | RSS: Main: 145412096 08:25:59 INFO - PROCESS | 20205 | 08:26:50 INFO - PROCESS | 20205 | 1469114810928 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] 08:28:58 INFO - PROCESS | 20205 | Cycle 1(1): loaded http://localhost:59141/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59141/tests/dromaeo/cssquery-ext.html) 08:28:59 INFO - PROCESS | 20205 | RSS: Main: 253546496 08:28:59 INFO - PROCESS | 20205 | 08:32:02 INFO - PROCESS | 20205 | Cycle 1(1): loaded http://localhost:59141/tests/dromaeo/cssquery-ext.html (next: http://localhost:59141/tests/dromaeo/cssquery-jquery.html) 08:32:02 INFO - PROCESS | 20205 | RSS: Main: 233836544 08:32:02 INFO - PROCESS | 20205 | 08:35:05 INFO - PROCESS | 20205 | Cycle 1(1): loaded http://localhost:59141/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59141/tests/dromaeo/cssquery-mootools.html) 08:35:05 INFO - PROCESS | 20205 | RSS: Main: 226500608 08:35:05 INFO - PROCESS | 20205 | 08:38:07 INFO - PROCESS | 20205 | Cycle 1(1): loaded http://localhost:59141/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59141/tests/dromaeo/cssquery-prototype.html) 08:38:07 INFO - PROCESS | 20205 | RSS: Main: 225357824 08:38:07 INFO - PROCESS | 20205 | 08:41:10 INFO - PROCESS | 20205 | Cycle 1(1): loaded http://localhost:59141/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59141/tests/dromaeo/cssquery-yui.html) 08:41:11 INFO - PROCESS | 20205 | RSS: Main: 215883776 08:41:11 INFO - PROCESS | 20205 | 08:44:13 INFO - PROCESS | 20205 | Cycle 1(1): loaded http://localhost:59141/tests/dromaeo/cssquery-yui.html (next: http://localhost:59141/tests/dromaeo/cssquery-dojo.html) 08:44:14 INFO - PROCESS | 20205 | RSS: Main: 274698240 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | __start_tp_report 08:44:14 INFO - PROCESS | 20205 | _x_x_mozilla_page_load 08:44:14 INFO - PROCESS | 20205 | _x_x_mozilla_page_load_details 08:44:14 INFO - PROCESS | 20205 | |i|pagename|runs| 08:44:14 INFO - PROCESS | 20205 | |0;dojo.html;3306.9503957440634;3329.983350083249;3342.4484959981605;3353.513740507625;3373.9372097789196;5394.056040192965;5482.396936337005;5487.259220005293;5494.80768173114;5495.725213739317;5455.618106732531;5520.779168833242;5535.529479994206;5540.972295138528;5555.861103472405;4926.3842019747535;4981.0037992401485;4998.925016124761;5040.117979353617;5072.72100034498;4871.366722326093;5000.7249601271915;5039.042581909425;5039.19372900336;5041.546260836524;4975.054739599483;5000.374953130859;5001.874953126165;5011;5018.698878067309;5087.516685914832;5093.363329583802;5101.336826212569;5103.464136265676;5132.486751324856;4832.73419961902;4913.287573301851;4948.158813001798;4984.5264699853815;5003.3495645565845;5299.364076310829;5328.0675881720545;5368.141097424394;5380.569554435636;5391.541718953855;5839.9124013139835;5889.941100588982;5891.8527036824;5891.970540147272;5898.321693005317;5788.942110578882;5827.912581311284;5859.941400585983;5902.32123305821;5936.347001829795;5507.256520369739;5517.69652669103;5533.280673512458;5550.583706222009;5568.888622227614;5773.422657734193;6080.301918440081;6085.509050282709;6109.205803245639;6127.172831667757;5676.035074037423;5708.143778433268;5727.3699893011735;5819.970900145557;5841.036229022248;5886.352501224862;5887.76448942046;5889.94110058894;5917.082852157922;5918.260217472816;5567.4432556744005;5728.369879313273;5866.2960444746905;5871.972404829223;5874.118882167709;5755;5868;5868.882622347529;5902.996490596609;5905.557083218776;4542.11428771393;4550.749708765983;4551.112533056088;4551.294549344857;4574.565416285448;3971;3971.3050216212046;3996.4005399189773;4079.73481723687;4116.547179810218;5531.0873705838085;5548.250986116823;5553.139263414178;5556.749946252428;5580.107182850725;5841.7371218295275;5865.853353666193;5867.853303667357;5877.52979761618;5907.1434641976675;5441.945580544144;5516.475934786189;5552.194931734956;5562.3881373048935;5578.804741834017;5009.799608015638;5018.4981501849525;5042.445331013586;5051.87370315745;5051.974740126276;6127.135312147439;6127.34224287047;6127.34224287047;6138.32709113608;6139.584134579375;5719.256496655408;5779.884402311846;5837.270341207349;5857.033589457862;5914.58597898143;6122.265328160649;6125.387461253839;6128.938710613015;6136.171616831671;6137.171481849893;5515.917261241165;5711.3431955324;5727.7422515986045;5735.799247026501;5739.339975902658;5686.60193786431;5708.5147762439;5750.827475175752;5805.767769289181;5809.186713860147;5641.041023026013;5666.9716651416475;5676.347220069745;5676.460736230051;5679.148127780701;5550.4449555044175;5644.407337229532;5656.745446454837;5666.263385759825;5669.489745922887;5607.635503692247;5671.829845104653;5674.602777805679;5675.035244008446;5722.570807189394;5794.072948328248;5808.448197421239;5809.099589563624;5821.679807610622;5831.096180092092;5529.834104976857;5544.750486228048;5583.860403489945;5682.659040457585;5707.971460142673 08:44:14 INFO - PROCESS | 20205 | |1;ext.html;606.8149214489579;652.2433976587158;653.542520235835;656.960582365058;666.1340257665034;32621.51067733983;35975.460368094464;46600.76699616497;47977.28034079492;48147.759261203646;49400.25899611509;49669.50330496694;49731.254031189565;49734.253986190146;49820.25269620959;9043.457392556435;9171.770705732377;9185.311101667368;9186.173244408003;9186.86219706705;8281.792955176139;10731.78536429275;10973.177011724152;10999.505022273977;11013.889861101366;10792.298500597437;10902.345859248471;10916.962888525577;11247.83128253077;11251.324920504796;8261.297789687824;8463.4075614707;11028;11097.38964356956;11119.610813621484;8512.404131710782;10731.624393146281;11076.612318568812;11076.778464430667;11461.828072578917;46607.53392466066;46991;47031.29453058207;47032.5296747035;47095.5290447098;3675.981620091909;3808.200277941608;3939.980300098481;4093.1199792044854;4429.886597682299;36173.81913090444;36401.27197456062;36483.81758091219;36692;36885;4741.620670346364;4753.595944344735;4833.1058754130245;4860.975695121537;4934.629902757271;403629;404829;405126;405968;407107;71844;72742.63628681928;72857.2714272861;72930.27069729341;73083.63458182674;38636.6136338633;39477.60522394797;39534;41170.794146029075;76036.23963760403;16589.91705041491;16624.6675066498;16681.416150434678;16693.749593756103;16697.916510417614;12653.74692506163;13231.66920826987;13442.865571344357;13540.526081587097;13592;22526.436839079153;22625.54748905013;22648.433789155402;22652.660210096863;22687.205947791746;7943.44395892282;8111.716089936942;8221.54781487012;8356.373272004625;8464.40749147566;995.1690338567378;1082.577794660083;1087.3638921231168;1114.6878873915316;1182.0897908610323;34261;35133.82433087818;35142;35264;35572.46641300383;1122.893325134111;1150.1603829204728;1189.958351457712;1193.612076075279;1200.2618389690278;18440;18476.168572414274;18671.439856804318;18683.90658046701;18711.906440467712;598780;598879;599168;599869;605810;67781.66109169522;68482.3151768476;68596.6570167146;68677;68866.311336887;1370.842353129403;1487.2935355705954;1512.4026009726097;1515.7574788034083;1528.9006214596016;34612.30775384428;35316.64683353133;35529;35977;36754.448683270304;57146.714266428404;57613;57654.13518797305;57791.55521112006;57832.71083644555;430098;431155;431350;431354;434370;28373.716262837934;29558.40883182367;31657.36685266236;31663.050108496784;33040.50439243365;53970.19044714254;54402.18396724131;54875.17687234615;55391.16913246224;55624.44375556193;44797;45084.54915450935;45294.77352613216;45656.60399976036;46237.53762462467;44131.33802993022;44451.55548444474;45079.32381014222;45645.771771140935;45894;44483.332750009424;45386.77306613446;45400.77299613481;45501;45518.08963820771;37510.8124459376;39652.80173599113;39965;40546.39180412355;40931.97670058272;30128.69871301259;30324.84837575798;30499.695003050572;30500.84749576238;30530.694693052785 08:44:14 INFO - PROCESS | 20205 | |2;jquery.html;404.31266846361183;433.7267521461479;435.2752666809762;437.2697595016322;443.323931005217;19713.704294435578;19825.107870145843;20335.389938301876;20357.592848143027;20599.07304171312;16755.748663770053;18441.446756597292;19171.808281917176;19708;19781.307654232103;5764.394738552454;6013.308469525995;6016.608920420181;6018.307894592134;6019.518438524908;4357.673174511909;4428.0701052778795;5870.0314448116005;5872.17789509469;6511.023346498016;6579.703913323912;6598.340165983412;6604.3725846044545;6611.570247933869;6614.040964060232;6478.74085036598;6518.380753828379;6557.049227861981;6576.0793488911595;6593.3736295051895;3907.531096268466;5480.534154596864;5828.446297601721;5861.003629382973;5921.615095018853;22613.54772904549;24441.755582444304;24485;24789.5042099157;24880.25359239226;2564.6537717408096;2634.9473010539873;2754.73829986151;2791.8464484453348;2829.7028811974856;15543.766843497357;16236.75644865328;16401.917990409973;16494.42269520561;16813.663726725397;2057.5884823035453;2147.8174355179826;2148.301801914384;2234.7094877665963;2302.2172461363048;70296.74762207031;90097;90793.54603227008;96771.51614241884;107321.46339268332;17966.91016544935;18391.81608183928;18625.254989800254;18722.344717934808;19010.904945475184;34877.30245395078;34898.127546811535;35256.82371588177;35372.469412958315;35612.46581301282;9656.163174093219;9901.801963960681;9975.950120249498;10070.848937265946;10146.746331341628;8338.874916876252;8519.446235994641;8572.571371431404;8587.540118179924;8879.378443509022;9246.95376523113;9428.292878034175;9434.49048152306;9499.287553433382;9555.569999350046;3437.016326326836;3623.0942264433893;3672.182269776478;3751.390558667864;3760.8683696070507;954.2223088183108;1003.287665757306;1003.7942221844504;1072.1369297715257;1113.582406597526;18842.81157188438;19290.22839086449;19502;19565;19896.303629372902;1037.113268155731;1044.5769463367349;1082.5940272397852;1082.9187810914214;1111.666500049998;10509.737256568647;10757.515911783988;10817;10959.397233152253;10986.615468458725;393681;395238;399653;533874;535533;86041.56979215064;99850.5007474958;101650;102230;103137.48431257946;514.0849288266891;532.1884126706803;536.7960175133425;537.8951104534657;538.7225578826907;11171.888281117084;11662.416879155839;12100.515979360727;12170.45232964501;12310;42162.78918605387;42629;42811.7859410701;43991.56008439875;44429.777851110535;52357;53354.1996870055;56132.71933640305;61321;62571.687141565824;12753.234805911676;13631.727365452438;13638.318084095557;13689.520866769615;13717.45130194781;13742.725145497234;13918.304084795518;14259.144451332953;14296.356663949939;14989.925050374679;12169.20900141488;12432.254064756144;13006.739865202831;13121.671958201121;13182.538611148564;12406.75811241659;12804.10371274002;12863.549775757803;12930.353482326034;13241.404136814059;12153.1492795507;12507;12514.18657787241;12565.748685026065;12750.298733569743;12990.805137922749;13025.153365031243;13249.668758281121;13253.403596838358;13496.797548036984;12330.075244356529;12453.377331133588;12826.102172847812;12874.098813082994;12942.482300707867 08:44:14 INFO - PROCESS | 20205 | |3;mootools.html;1387.6045327081783;1575.2989919485826;1616.2726772952171;1799.1723807048756;1915.2434788258638;9477.668281610146;9886.110250077492;9913.15738162257;10226;10228.335158214712;9903.356281841676;10070.345427547223;10096.343737657047;10099.141572966306;10099.343542669722;2533.6832895888015;2562.423454722691;2563.51293254282;2566.0120853471453;2567.229831050677;2536.315194897386;2563.9230823075336;2564.1153801938303;2564.807639427041;2565.7049439314533;2483.279848843842;2512.4095837478176;2534.353739796349;2539.263613552068;2541.313845261778;2420.9031638734427;2454.1655837015323;2458.5082983403213;2482.739312372202;2501.399664080626;2458.385403649088;2464.7904928081134;2467.864267465289;2478.491909158618;2531.392465808211;9898.208143348515;9942.453165075918;10035.09684128432;10045.598176072948;10176.287659863812;5795.217645617872;5930;5933.495652869511;5939.732712027969;5942.257217847769;3913.4716813230134;3956.287868183726;3961.9405708914387;3996.48045754053;4006.639402453793;1252.7932891072962;1255.2593969558004;1260.2249616485888;1267.4359909840125;1300.8438987321488;14313.42746290147;14491.565253042425;14582.562523124217;14588.270586470633;14589.6352591185;5035.219540971156;5092.898142037213;5151.407588127338;5164.9741751291;5194.92207616886;11096.056835169102;11133.832992505118;11134.27627204229;11139.053180479588;11146.609868654721;5741.913871291934;5766.308043034863;5790.10253410722;5793.102069179284;5796.3913789052385;4629.120467111238;4872.171730805771;5133.563647090039;5192.065428222956;5302.761375738101;4202.6847986400535;4203.306454435045;4230.302000169938;4266.338717498793;4314.180305741899;1236.4683186230006;1276.2533917658275;1293.5407930184838;1295.8768916952874;1300.3823183987531;839.3956351426963;882.2280504558511;928.8885333759993;1070.817960946641;1077.4343469678324;6466.741330346734;6640.701168447432;6758.526903116833;6780.423663988518;6888.965555172293;532.0237364436302;536.4742552298691;555.7110302642603;560.557159843727;710.9797440672651;4844.249141383091;4906.9263961040615;4946.579540739005;4949.57928576075;5021.723805190676;12823.615291541082;13107.737845243042;13186.076974611877;13250.536231232054;13292.867071329356;12104;12330.445129969174;12349.19730217533;13067.411966461532;13123;9248.676296329597;9388.483633400092;9415.576299066559;9426.198773104363;9480.620775169054;6526.836829079311;6681.799546013627;6759.357861003196;6821.727130914708;6849.48628852848;11516.769664606829;11717.238379505305;11890.108241882066;12021.579244726392;12036.819447708116;11759.353235572125;11908.821367679318;11947.984421323938;11999.700007499534;12292.692682683004;8840;8843.07147749477;8982.101789821227;9096.226820720049;9126.771830704285;9825.017498250118;9846.113849753556;9882.25883058759;9909.504524773876;9910.35582687123;8512.319014478702;8700.564971751513;8735.126487351214;8765.956170219108;9004.63981440735;8462.280706140047;8497.320214382959;8509.787255318666;8580.399372043898;8685.522296273766;7838.568878711498;7949.562774047483;8019.278264956182;8023.679052838054;8034.076081250731;5617.101263797773;5679.943200567942;5741.311042674905;5807.854803629774;5865;5531.363893152339;5561.249231353877;5581.386047534849;5679.233303504139;5832.3292821326095 08:44:14 INFO - PROCESS | 20205 | |4;prototype.html;353.47685425570154;360.9097725568608;363.6999475432768;367.8023807748702;369.08467001835464;5298.496642818926;5395.514403703666;5400.675959442437;5509.705219273473;5536.474034966677;5393.59548033897;5450.373207081177;5450.892990995134;5457.5633949284065;5471.261379713737;4234.491860976679;4248.3627455881715;4259.339802330644;4287.6569874410125;4306.35404689296;4041.4544036554985;4160.43834082398;4264.33902745075;4299.527052024275;4310.52584215736;4253.723507971988;4255.851045213418;4278.871633850989;4287;4291.334843099309;4238.495334156376;4268.103698223361;4271.8718438446895;4291.206126866539;4326.502452217972;3693.242885208526;3809.504764380613;3852;3852.672522835562;3856.1709232515145;5164.302819119446;5200;5201.505856943584;5233.842984710464;5236.528712415901;4838.782254798507;4856.39295088114;4866.707997520125;4870.586000189988;4872.220444728827;5436.945630543723;5611.961787069406;5640.435956404368;5657.1514272858985;5659.849257282248;4096.283150448689;4099.098198396348;4100.7334523255895;4137.441445404862;4141.751494910285;5915.905557471881;5928.229330187091;5973.506623344164;5975.342712301644;5992.43071908168;3985.880423587297;4180.519240287345;4184.581541845791;4213.873583792491;4235.661147108224;4244.766537840448;4313.223619748413;4332.740035597812;4336.848210312624;4338.457692788402;4913.041956818386;4920.917398172396;4930.679505832181;4942.332785073969;4974.676646017997;5462.672239665632;5620.803271885547;5677.432256774372;5756.5682573807135;5814;1360.9523666671619;1435.4904009076836;1468.3025562857558;1472.2859413184597;1474.3807600807686;935.4667839331515;942.688912658821;948.8139825218477;950.5437390052813;966.1787480641539;4892.828750993698;4904.852854414374;4945.208766597327;4951.876203094951;4952.727599981963;5183.948160518422;5195.493306940944;5239.607029472805;5259.421463638997;5297.218960046007;4813.181759100891;4950.455449900508;4954.804151322449;4968.776405061781;5035.521625445577;2783.6520434945633;2809.227462447843;2835.6247220098235;2841.94847906276;2844.3173638326653;5339.546138578265;5398.217258497496;5417.3770016447825;5422.267993820863;5449.438763781017;5261.5264626183825;5266.919566771104;5535.806246781343;5991.400859913973;6006.339302676702;6056.909146362808;6094.481969032682;6112.572119951561;6124.203853499017;6124.755009799558;5652.2086907833755;5893.882122357614;5894.469497745224;5907.2911250650195;5921.674307912934;5818.883622327445;5837.912431313619;5847.210626565359;5866.3547009828035;5884.941150588611;6068.4841788448475;6094.634321940698;6124.969375153096;6159.045347971071;6165.938340616536;4240.625249860088;4321.330193819963;4343.956560434355;4358.891027724332;4359.912801744011;5711;5811.476967072984;5888.793892213924;5932.436418540232;5936.198613187337;5773.595848290579;5776.306843178845;5781.68200748946;5790.594658373873;5802.245708057925;5749.885002299847;5753.769849205984;5773.037167363117;5799.159121927212;5806.2451881255165;5501.807436739695;5641.658711634625;5721.427857214246;5736.885262294814;5746.046344242977;5786.594938354443;5799.362070172193;5801.564882633904;5820.991757843537;5846.473817356458;5448.782048718007;5714.685692286798;5724.599278050497;5747.8563035924435;5761.452661997104 08:44:14 INFO - PROCESS | 20205 | |5;yui.html;800.4116974024095;816.7672213419177;894.6108442827368;895.5656506594302;900.3427497926509;15393.615159621;16648.001119932796;16655.916720416397;16667.583310417245;16671.249793759278;14064.367103480343;14073.718525629482;14105.506307279247;14107.929460352683;14111.012229143964;722.6892436252397;857.8370109679172;887.525174031893;892.6652505310509;893.9776505587347;896.6368620708623;900.7027680865332;901.4411065139623;903.1104362203224;903.439867282286;940.5908429833011;943.6225509796102;945.7588314979671;947.1286416496839;947.606743201574;744.1999850161071;915.9771005724843;930.8324501589711;931.2689538712146;936.4708939449189;690.126989358462;691.3984833195102;691.6645426967916;691.9031335613018;692.1521136607665;15166.924165379103;15173.696526069527;15254.618634534114;15302.387904483807;15305.540833775005;14650;14849.628759281104;14908.850911490854;15030.248487575578;15288.159151246677;12745.426455809511;13301.600951971457;13348.132371395828;13401.061925665208;13544.59366219015;4660.86017419478;4693.6010439112715;4696.342512048316;4742;4812.7352995585225;70419.29580704178;73232.26767732308;73816;76703.6164819178;83455.5827220866;12674.176178548365;12869;12953.417096230693;13081;13277.136986095873;23016.654750178426;23068.884655576614;23107.306780796604;23110;23132.53734925292;9090.181883630505;9139.360244782802;9373.297002724823;9469.10043545862;9471.66849160276;4817.349657796152;5006.949930500721;5009.24861270805;5063.215201643751;5171.3535808024;3918.941215881764;4025.376066709665;4028.5165780106577;4031.4355990161403;4197.9370309445385;725.2131437390385;726.9854602907913;733.5915043117228;735.9963200183964;743.7694314762441;774.8140446292849;788.2905385153293;815.4006804998321;823.8846561481397;826.9379796515285;10084.445355505517;10189.796204075878;10253.38479691206;10265.794684106277;10368.637097701545;703.3067320044336;807.6163822184533;814.8899898513744;835.4235577451539;841.381584535366;4342.652587793033;4604.792784324646;4643.791029403617;4820.108279968147;4846.151923413388;79089.20910790819;123216;128946;134765.3261733685;137480;84417;86662.56668716617;93565.53217234007;111206;114318.42840785743;76688.2331176681;85739.57130214309;89734.55132724295;90710.09289907017;91169;10284.432400776446;10425.582976680847;10669.839952400565;10731.40977246259;10763.569457221936;38489.422658660704;38621.80689096537;48359.5164048355;48742.26886596775;54018.40322331077;69586;77330.22669773456;78014.2198578007;79295;81017;13264.86735132675;13396.2632055234;13423.597292080862;13713.520026799015;14056.367463463965;15225.6193595161;15235.04506559053;15716;15853.365865365717;16128.838711612734;13638.522651707142;13978;14149;14381.856181438052;14468.638284042983;14546.781798273247;15106.924465377602;15160.696786064438;15465;15499.690006199588;14192.574222773334;14414.639634008814;14449.349779259748;14615.92692036533;14622.415103395744;11490.253133546294;11702.648920532398;11894.167408281683;11972.64082077539;12093.213941094153;11966.64100076999;12422.751544969231;12541;12670.683232919251;12888 08:44:14 INFO - PROCESS | 20205 | __end_tp_report 08:44:14 INFO - PROCESS | 20205 | __start_cc_report 08:44:14 INFO - PROCESS | 20205 | _x_x_mozilla_cycle_collect,880 08:44:14 INFO - PROCESS | 20205 | __end_cc_report 08:44:14 INFO - PROCESS | 20205 | __startTimestamp1469115854017__endTimestamp 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | ------- Summary: start ------- 08:44:14 INFO - PROCESS | 20205 | Number of tests: 6 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | [#0] dojo.html Cycles:165 Average:5449.57 Median:5641.04 stddev:592.51 (10.5%) stddev-sans-first:569.98 08:44:14 INFO - PROCESS | 20205 | Values: 3307.0 3330.0 3342.4 3353.5 3373.9 5394.1 5482.4 5487.3 5494.8 5495.7 5455.6 5520.8 5535.5 5541.0 5555.9 4926.4 4981.0 4998.9 5040.1 5072.7 4871.4 5000.7 5039.0 5039.2 5041.5 4975.1 5000.4 5001.9 5011.0 5018.7 5087.5 5093.4 5101.3 5103.5 5132.5 4832.7 4913.3 4948.2 4984.5 5003.3 5299.4 5328.1 5368.1 5380.6 5391.5 5839.9 5889.9 5891.9 5892.0 5898.3 5788.9 5827.9 5859.9 5902.3 5936.3 5507.3 5517.7 5533.3 5550.6 5568.9 5773.4 6080.3 6085.5 6109.2 6127.2 5676.0 5708.1 5727.4 5820.0 5841.0 5886.4 5887.8 5889.9 5917.1 5918.3 5567.4 5728.4 5866.3 5872.0 5874.1 5755.0 5868.0 5868.9 5903.0 5905.6 4542.1 4550.7 4551.1 4551.3 4574.6 3971.0 3971.3 3996.4 4079.7 4116.5 5531.1 5548.3 5553.1 5556.7 5580.1 5841.7 5865.9 5867.9 5877.5 5907.1 5441.9 5516.5 5552.2 5562.4 5578.8 5009.8 5018.5 5042.4 5051.9 5052.0 6127.1 6127.3 6127.3 6138.3 6139.6 5719.3 5779.9 5837.3 5857.0 5914.6 6122.3 6125.4 6128.9 6136.2 6137.2 5515.9 5711.3 5727.7 5735.8 5739.3 5686.6 5708.5 5750.8 5805.8 5809.2 5641.0 5667.0 5676.3 5676.5 5679.1 5550.4 5644.4 5656.7 5666.3 5669.5 5607.6 5671.8 5674.6 5675.0 5722.6 5794.1 5808.4 5809.1 5821.7 5831.1 5529.8 5544.8 5583.9 5682.7 5708.0 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | [#1] ext.html Cycles:180 Average:65685.09 Median:406537.50 stddev:129084.33 (31.8%) stddev-sans-first:129353.97 08:44:14 INFO - PROCESS | 20205 | Values: 606.8 652.2 653.5 657.0 666.1 32621.5 35975.5 46600.8 47977.3 48147.8 49400.3 49669.5 49731.3 49734.3 49820.3 9043.5 9171.8 9185.3 9186.2 9186.9 8281.8 10731.8 10973.2 10999.5 11013.9 10792.3 10902.3 10917.0 11247.8 11251.3 8261.3 8463.4 11028.0 11097.4 11119.6 8512.4 10731.6 11076.6 11076.8 11461.8 46607.5 46991.0 47031.3 47032.5 47095.5 3676.0 3808.2 3940.0 4093.1 4429.9 36173.8 36401.3 36483.8 36692.0 36885.0 4741.6 4753.6 4833.1 4861.0 4934.6 403629.0 404829.0 405126.0 405968.0 407107.0 71844.0 72742.6 72857.3 72930.3 73083.6 38636.6 39477.6 39534.0 41170.8 76036.2 16589.9 16624.7 16681.4 16693.7 16697.9 12653.7 13231.7 13442.9 13540.5 13592.0 22526.4 22625.5 22648.4 22652.7 22687.2 7943.4 8111.7 8221.5 8356.4 8464.4 995.2 1082.6 1087.4 1114.7 1182.1 34261.0 35133.8 35142.0 35264.0 35572.5 1122.9 1150.2 1190.0 1193.6 1200.3 18440.0 18476.2 18671.4 18683.9 18711.9 598780.0 598879.0 599168.0 599869.0 605810.0 67781.7 68482.3 68596.7 68677.0 68866.3 1370.8 1487.3 1512.4 1515.8 1528.9 34612.3 35316.6 35529.0 35977.0 36754.4 57146.7 57613.0 57654.1 57791.6 57832.7 430098.0 431155.0 431350.0 431354.0 434370.0 28373.7 29558.4 31657.4 31663.1 33040.5 53970.2 54402.2 54875.2 55391.2 55624.4 44797.0 45084.5 45294.8 45656.6 46237.5 44131.3 44451.6 45079.3 45645.8 45894.0 44483.3 45386.8 45400.8 45501.0 45518.1 37510.8 39652.8 39965.0 40546.4 40932.0 30128.7 30324.8 30499.7 30500.8 30530.7 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | [#2] jquery.html Cycles:180 Average:29948.72 Median:13371.99 stddev:75693.17 (566.1%) stddev-sans-first:75873.00 08:44:14 INFO - PROCESS | 20205 | Values: 404.3 433.7 435.3 437.3 443.3 19713.7 19825.1 20335.4 20357.6 20599.1 16755.7 18441.4 19171.8 19708.0 19781.3 5764.4 6013.3 6016.6 6018.3 6019.5 4357.7 4428.1 5870.0 5872.2 6511.0 6579.7 6598.3 6604.4 6611.6 6614.0 6478.7 6518.4 6557.0 6576.1 6593.4 3907.5 5480.5 5828.4 5861.0 5921.6 22613.5 24441.8 24485.0 24789.5 24880.3 2564.7 2634.9 2754.7 2791.8 2829.7 15543.8 16236.8 16401.9 16494.4 16813.7 2057.6 2147.8 2148.3 2234.7 2302.2 70296.7 90097.0 90793.5 96771.5 107321.5 17966.9 18391.8 18625.3 18722.3 19010.9 34877.3 34898.1 35256.8 35372.5 35612.5 9656.2 9901.8 9976.0 10070.8 10146.7 8338.9 8519.4 8572.6 8587.5 8879.4 9247.0 9428.3 9434.5 9499.3 9555.6 3437.0 3623.1 3672.2 3751.4 3760.9 954.2 1003.3 1003.8 1072.1 1113.6 18842.8 19290.2 19502.0 19565.0 19896.3 1037.1 1044.6 1082.6 1082.9 1111.7 10509.7 10757.5 10817.0 10959.4 10986.6 393681.0 395238.0 399653.0 533874.0 535533.0 86041.6 99850.5 101650.0 102230.0 103137.5 514.1 532.2 536.8 537.9 538.7 11171.9 11662.4 12100.5 12170.5 12310.0 42162.8 42629.0 42811.8 43991.6 44429.8 52357.0 53354.2 56132.7 61321.0 62571.7 12753.2 13631.7 13638.3 13689.5 13717.5 13742.7 13918.3 14259.1 14296.4 14989.9 12169.2 12432.3 13006.7 13121.7 13182.5 12406.8 12804.1 12863.5 12930.4 13241.4 12153.1 12507.0 12514.2 12565.7 12750.3 12990.8 13025.2 13249.7 13253.4 13496.8 12330.1 12453.4 12826.1 12874.1 12942.5 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | [#3] mootools.html Cycles:180 Average:6578.96 Median:4471.65 stddev:3895.73 (87.1%) stddev-sans-first:3887.12 08:44:14 INFO - PROCESS | 20205 | Values: 1387.6 1575.3 1616.3 1799.2 1915.2 9477.7 9886.1 9913.2 10226.0 10228.3 9903.4 10070.3 10096.3 10099.1 10099.3 2533.7 2562.4 2563.5 2566.0 2567.2 2536.3 2563.9 2564.1 2564.8 2565.7 2483.3 2512.4 2534.4 2539.3 2541.3 2420.9 2454.2 2458.5 2482.7 2501.4 2458.4 2464.8 2467.9 2478.5 2531.4 9898.2 9942.5 10035.1 10045.6 10176.3 5795.2 5930.0 5933.5 5939.7 5942.3 3913.5 3956.3 3961.9 3996.5 4006.6 1252.8 1255.3 1260.2 1267.4 1300.8 14313.4 14491.6 14582.6 14588.3 14589.6 5035.2 5092.9 5151.4 5165.0 5194.9 11096.1 11133.8 11134.3 11139.1 11146.6 5741.9 5766.3 5790.1 5793.1 5796.4 4629.1 4872.2 5133.6 5192.1 5302.8 4202.7 4203.3 4230.3 4266.3 4314.2 1236.5 1276.3 1293.5 1295.9 1300.4 839.4 882.2 928.9 1070.8 1077.4 6466.7 6640.7 6758.5 6780.4 6889.0 532.0 536.5 555.7 560.6 711.0 4844.2 4906.9 4946.6 4949.6 5021.7 12823.6 13107.7 13186.1 13250.5 13292.9 12104.0 12330.4 12349.2 13067.4 13123.0 9248.7 9388.5 9415.6 9426.2 9480.6 6526.8 6681.8 6759.4 6821.7 6849.5 11516.8 11717.2 11890.1 12021.6 12036.8 11759.4 11908.8 11948.0 11999.7 12292.7 8840.0 8843.1 8982.1 9096.2 9126.8 9825.0 9846.1 9882.3 9909.5 9910.4 8512.3 8700.6 8735.1 8766.0 9004.6 8462.3 8497.3 8509.8 8580.4 8685.5 7838.6 7949.6 8019.3 8023.7 8034.1 5617.1 5679.9 5741.3 5807.9 5865.0 5531.4 5561.2 5581.4 5679.2 5832.3 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | [#4] prototype.html Cycles:180 Average:4752.07 Median:5282.07 stddev:1394.01 (26.4%) stddev-sans-first:1358.26 08:44:14 INFO - PROCESS | 20205 | Values: 353.5 360.9 363.7 367.8 369.1 5298.5 5395.5 5400.7 5509.7 5536.5 5393.6 5450.4 5450.9 5457.6 5471.3 4234.5 4248.4 4259.3 4287.7 4306.4 4041.5 4160.4 4264.3 4299.5 4310.5 4253.7 4255.9 4278.9 4287.0 4291.3 4238.5 4268.1 4271.9 4291.2 4326.5 3693.2 3809.5 3852.0 3852.7 3856.2 5164.3 5200.0 5201.5 5233.8 5236.5 4838.8 4856.4 4866.7 4870.6 4872.2 5436.9 5612.0 5640.4 5657.2 5659.8 4096.3 4099.1 4100.7 4137.4 4141.8 5915.9 5928.2 5973.5 5975.3 5992.4 3985.9 4180.5 4184.6 4213.9 4235.7 4244.8 4313.2 4332.7 4336.8 4338.5 4913.0 4920.9 4930.7 4942.3 4974.7 5462.7 5620.8 5677.4 5756.6 5814.0 1361.0 1435.5 1468.3 1472.3 1474.4 935.5 942.7 948.8 950.5 966.2 4892.8 4904.9 4945.2 4951.9 4952.7 5183.9 5195.5 5239.6 5259.4 5297.2 4813.2 4950.5 4954.8 4968.8 5035.5 2783.7 2809.2 2835.6 2841.9 2844.3 5339.5 5398.2 5417.4 5422.3 5449.4 5261.5 5266.9 5535.8 5991.4 6006.3 6056.9 6094.5 6112.6 6124.2 6124.8 5652.2 5893.9 5894.5 5907.3 5921.7 5818.9 5837.9 5847.2 5866.4 5884.9 6068.5 6094.6 6125.0 6159.0 6165.9 4240.6 4321.3 4344.0 4358.9 4359.9 5711.0 5811.5 5888.8 5932.4 5936.2 5773.6 5776.3 5781.7 5790.6 5802.2 5749.9 5753.8 5773.0 5799.2 5806.2 5501.8 5641.7 5721.4 5736.9 5746.0 5786.6 5799.4 5801.6 5821.0 5846.5 5448.8 5714.7 5724.6 5747.9 5761.5 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - PROCESS | 20205 | [#5] yui.html Cycles:180 Average:21307.41 Median:4029.98 stddev:30529.74 (757.6%) stddev-sans-first:30576.55 08:44:14 INFO - PROCESS | 20205 | Values: 800.4 816.8 894.6 895.6 900.3 15393.6 16648.0 16655.9 16667.6 16671.2 14064.4 14073.7 14105.5 14107.9 14111.0 722.7 857.8 887.5 892.7 894.0 896.6 900.7 901.4 903.1 903.4 940.6 943.6 945.8 947.1 947.6 744.2 916.0 930.8 931.3 936.5 690.1 691.4 691.7 691.9 692.2 15166.9 15173.7 15254.6 15302.4 15305.5 14650.0 14849.6 14908.9 15030.2 15288.2 12745.4 13301.6 13348.1 13401.1 13544.6 4660.9 4693.6 4696.3 4742.0 4812.7 70419.3 73232.3 73816.0 76703.6 83455.6 12674.2 12869.0 12953.4 13081.0 13277.1 23016.7 23068.9 23107.3 23110.0 23132.5 9090.2 9139.4 9373.3 9469.1 9471.7 4817.3 5006.9 5009.2 5063.2 5171.4 3918.9 4025.4 4028.5 4031.4 4197.9 725.2 727.0 733.6 736.0 743.8 774.8 788.3 815.4 823.9 826.9 10084.4 10189.8 10253.4 10265.8 10368.6 703.3 807.6 814.9 835.4 841.4 4342.7 4604.8 4643.8 4820.1 4846.2 79089.2 123216.0 128946.0 134765.3 137480.0 84417.0 86662.6 93565.5 111206.0 114318.4 76688.2 85739.6 89734.6 90710.1 91169.0 10284.4 10425.6 10669.8 10731.4 10763.6 38489.4 38621.8 48359.5 48742.3 54018.4 69586.0 77330.2 78014.2 79295.0 81017.0 13264.9 13396.3 13423.6 13713.5 14056.4 15225.6 15235.0 15716.0 15853.4 16128.8 13638.5 13978.0 14149.0 14381.9 14468.6 14546.8 15106.9 15160.7 15465.0 15499.7 14192.6 14414.6 14449.3 14615.9 14622.4 11490.3 11702.6 11894.2 11972.6 12093.2 11966.6 12422.8 12541.0 12670.7 12888.0 08:44:14 INFO - PROCESS | 20205 | -------- Summary: end -------- 08:44:14 INFO - PROCESS | 20205 | 08:44:14 INFO - TEST-INFO | 20205: exit 0 08:44:14 INFO - TEST-OK | dromaeo_css | took 1114206ms 08:44:14 INFO - TEST-START | kraken 08:44:14 INFO - operating with platform_type : linux_ 08:44:14 INFO - Initialising browser for kraken test... 08:44:14 INFO - TEST-INFO | started process 20389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_3JqF3/profile http://localhost:59141/getInfo.html) 08:44:19 INFO - PROCESS | 20389 | __metrics Screen width/height:1600/1200 08:44:19 INFO - PROCESS | 20389 | colorDepth:24 08:44:19 INFO - PROCESS | 20389 | Browser inner width/height: 1024/697 08:44:19 INFO - PROCESS | 20389 | __metrics 08:44:20 INFO - PROCESS | 20389 | 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" 08:44:20 ERROR - PROCESS | 20389 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:44:21 INFO - TEST-INFO | 20389: exit 0 08:44:21 INFO - Browser initialized. 08:44:21 INFO - Running cycle 1/1 for kraken test... 08:44:21 INFO - TEST-INFO | started process 20479 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_3JqF3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:33 INFO - PROCESS | 20479 | RSS: Main: 149065728 08:44:33 INFO - PROCESS | 20479 | 08:44:35 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59141/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:36 INFO - PROCESS | 20479 | RSS: Main: 198107136 08:44:36 INFO - PROCESS | 20479 | 08:44:39 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59141/tests/kraken/driver.html?testName=audio-dft) 08:44:39 INFO - PROCESS | 20479 | RSS: Main: 191492096 08:44:39 INFO - PROCESS | 20479 | 08:44:42 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59141/tests/kraken/driver.html?testName=audio-fft) 08:44:43 INFO - PROCESS | 20479 | RSS: Main: 282357760 08:44:43 INFO - PROCESS | 20479 | 08:44:45 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59141/tests/kraken/driver.html?testName=audio-oscillator) 08:44:45 INFO - PROCESS | 20479 | RSS: Main: 190701568 08:44:45 INFO - PROCESS | 20479 | 08:44:47 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59141/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:44:48 INFO - PROCESS | 20479 | RSS: Main: 191311872 08:44:48 INFO - PROCESS | 20479 | 08:44:51 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59141/tests/kraken/driver.html?testName=imaging-darkroom) 08:44:52 INFO - PROCESS | 20479 | RSS: Main: 226271232 08:44:52 INFO - PROCESS | 20479 | 08:44:55 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59141/tests/kraken/driver.html?testName=imaging-desaturate) 08:44:56 INFO - PROCESS | 20479 | RSS: Main: 225193984 08:44:56 INFO - PROCESS | 20479 | 08:44:58 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59141/tests/kraken/driver.html?testName=json-parse-financial) 08:44:59 INFO - PROCESS | 20479 | RSS: Main: 225476608 08:44:59 INFO - PROCESS | 20479 | 08:45:01 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59141/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:45:01 INFO - PROCESS | 20479 | RSS: Main: 204800000 08:45:01 INFO - PROCESS | 20479 | 08:45:03 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:45:03 INFO - PROCESS | 20479 | RSS: Main: 205393920 08:45:03 INFO - PROCESS | 20479 | 08:45:05 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:45:06 INFO - PROCESS | 20479 | RSS: Main: 209162240 08:45:06 INFO - PROCESS | 20479 | 08:45:09 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:45:09 INFO - PROCESS | 20479 | RSS: Main: 215281664 08:45:09 INFO - PROCESS | 20479 | 08:45:12 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:45:13 INFO - PROCESS | 20479 | RSS: Main: 215396352 08:45:13 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | Cycle 1(1): loaded http://localhost:59141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59141/tests/kraken/driver.html?testName=ai-astar) 08:45:14 INFO - PROCESS | 20479 | RSS: Main: 258166784 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | __start_tp_report 08:45:14 INFO - PROCESS | 20479 | _x_x_mozilla_page_load 08:45:14 INFO - PROCESS | 20479 | _x_x_mozilla_page_load_details 08:45:14 INFO - PROCESS | 20479 | |i|pagename|runs| 08:45:14 INFO - PROCESS | 20479 | |0;ai-astar;110;103;109;110;109;108;108;110;112;111 08:45:14 INFO - PROCESS | 20479 | |1;audio-beat-detection;158;124;133;182;125;168;146;126;179;123 08:45:14 INFO - PROCESS | 20479 | |2;audio-dft;161;161;160;166;160;159;161;160;159;159 08:45:14 INFO - PROCESS | 20479 | |3;audio-fft;75;75;76;127;75;77;76;76;101;74 08:45:14 INFO - PROCESS | 20479 | |4;audio-oscillator;84;85;116;84;84;84;117;84;84;128 08:45:14 INFO - PROCESS | 20479 | |5;imaging-gaussian-blur;117;118;117;118;117;116;117;116;116;117 08:45:14 INFO - PROCESS | 20479 | |6;imaging-darkroom;146;146;145;147;147;145;146;146;146;149 08:45:14 INFO - PROCESS | 20479 | |7;imaging-desaturate;94;94;95;94;95;95;94;95;95;95 08:45:14 INFO - PROCESS | 20479 | |8;json-parse-financial;52;51;52;52;52;53;52;53;54;52 08:45:14 INFO - PROCESS | 20479 | |9;json-stringify-tinderbox;59;60;60;60;60;61;60;60;60;60 08:45:14 INFO - PROCESS | 20479 | |10;stanford-crypto-aes;77;75;75;76;78;77;77;77;105;77 08:45:14 INFO - PROCESS | 20479 | |11;stanford-crypto-ccm;128;125;119;119;118;117;128;121;137;118 08:45:14 INFO - PROCESS | 20479 | |12;stanford-crypto-pbkdf2;159;156;158;158;156;154;160;156;154;165 08:45:14 INFO - PROCESS | 20479 | |13;stanford-crypto-sha256-iterative;64;62;64;61;61;62;61;62;61;60 08:45:14 INFO - PROCESS | 20479 | __end_tp_report 08:45:14 INFO - PROCESS | 20479 | __start_cc_report 08:45:14 INFO - PROCESS | 20479 | _x_x_mozilla_cycle_collect,1580 08:45:14 INFO - PROCESS | 20479 | __end_cc_report 08:45:14 INFO - PROCESS | 20479 | __startTimestamp1469115914888__endTimestamp 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | ------- Summary: start ------- 08:45:14 INFO - PROCESS | 20479 | Number of tests: 14 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:2.45 (2.2%) stddev-sans-first:2.57 08:45:14 INFO - PROCESS | 20479 | Values: 110.0 103.0 109.0 110.0 109.0 108.0 108.0 110.0 112.0 111.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#1] audio-beat-detection Cycles:10 Average:146.40 Median:152.00 stddev:23.65 (15.6%) stddev-sans-first:24.71 08:45:14 INFO - PROCESS | 20479 | Values: 158.0 124.0 133.0 182.0 125.0 168.0 146.0 126.0 179.0 123.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:2.07 (1.3%) stddev-sans-first:2.19 08:45:14 INFO - PROCESS | 20479 | Values: 161.0 161.0 160.0 166.0 160.0 159.0 161.0 160.0 159.0 159.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.50 stddev:17.37 (23.0%) stddev-sans-first:18.17 08:45:14 INFO - PROCESS | 20479 | Values: 75.0 75.0 76.0 127.0 75.0 77.0 76.0 76.0 101.0 74.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:84.00 stddev:17.76 (21.1%) stddev-sans-first:18.39 08:45:14 INFO - PROCESS | 20479 | Values: 84.0 85.0 116.0 84.0 84.0 84.0 117.0 84.0 84.0 128.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 08:45:14 INFO - PROCESS | 20479 | Values: 117.0 118.0 117.0 118.0 117.0 116.0 117.0 116.0 116.0 117.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:1.16 (0.8%) stddev-sans-first:1.22 08:45:14 INFO - PROCESS | 20479 | Values: 146.0 146.0 145.0 147.0 147.0 145.0 146.0 146.0 146.0 149.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:45:14 INFO - PROCESS | 20479 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 08:45:14 INFO - PROCESS | 20479 | Values: 52.0 51.0 52.0 52.0 52.0 53.0 52.0 53.0 54.0 52.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.47 (0.8%) stddev-sans-first:0.33 08:45:14 INFO - PROCESS | 20479 | Values: 59.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:77.00 stddev:9.05 (11.7%) stddev-sans-first:9.55 08:45:14 INFO - PROCESS | 20479 | Values: 77.0 75.0 75.0 76.0 78.0 77.0 77.0 77.0 105.0 77.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:123.00 stddev:6.43 (5.2%) stddev-sans-first:6.56 08:45:14 INFO - PROCESS | 20479 | Values: 128.0 125.0 119.0 119.0 118.0 117.0 128.0 121.0 137.0 118.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.60 Median:158.00 stddev:3.27 (2.1%) stddev-sans-first:3.43 08:45:14 INFO - PROCESS | 20479 | Values: 159.0 156.0 158.0 158.0 156.0 154.0 160.0 156.0 154.0 165.0 08:45:14 INFO - PROCESS | 20479 | 08:45:14 INFO - PROCESS | 20479 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.32 (2.1%) stddev-sans-first:1.13 08:45:14 INFO - PROCESS | 20479 | Values: 64.0 62.0 64.0 61.0 61.0 62.0 61.0 62.0 61.0 60.0 08:45:14 INFO - PROCESS | 20479 | -------- Summary: end -------- 08:45:14 INFO - PROCESS | 20479 | 08:45:15 INFO - TEST-INFO | 20479: exit 0 08:45:15 INFO - TEST-OK | kraken | took 61137ms 08:45:15 INFO - SUITE-END | took 1175s 08:45:16 INFO - Completed test suite (00:19:35) 08:45:16 INFO - javascript benchmark 08:45:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.9503957440634, 3329.983350083249, 3342.4484959981605, 3353.513740507625, 3373.9372097789196, 5394.056040192965, 5482.396936337005, 5487.259220005293, 5494.80768173114, 5495.725213739317, 5455.618106732531, 5520.779168833242, 5535.529479994206, 5540.972295138528, 5555.861103472405, 4926.3842019747535, 4981.0037992401485, 4998.925016124761, 5040.117979353617, 5072.72100034498, 4871.366722326093, 5000.7249601271915, 5039.042581909425, 5039.19372900336, 5041.546260836524, 4975.054739599483, 5000.374953130859, 5001.874953126165, 5011.0, 5018.698878067309, 5087.516685914832, 5093.363329583802, 5101.336826212569, 5103.464136265676, 5132.486751324856, 4832.73419961902, 4913.287573301851, 4948.158813001798, 4984.5264699853815, 5003.3495645565845, 5299.364076310829, 5328.0675881720545, 5368.141097424394, 5380.569554435636, 5391.541718953855, 5839.9124013139835, 5889.941100588982, 5891.8527036824, 5891.970540147272, 5898.321693005317, 5788.942110578882, 5827.912581311284, 5859.941400585983, 5902.32123305821, 5936.347001829795, 5507.256520369739, 5517.69652669103, 5533.280673512458, 5550.583706222009, 5568.888622227614, 5773.422657734193, 6080.301918440081, 6085.509050282709, 6109.205803245639, 6127.172831667757, 5676.035074037423, 5708.143778433268, 5727.3699893011735, 5819.970900145557, 5841.036229022248, 5886.352501224862, 5887.76448942046, 5889.94110058894, 5917.082852157922, 5918.260217472816, 5567.4432556744005, 5728.369879313273, 5866.2960444746905, 5871.972404829223, 5874.118882167709, 5755.0, 5868.0, 5868.882622347529, 5902.996490596609, 5905.557083218776, 4542.11428771393, 4550.749708765983, 4551.112533056088, 4551.294549344857, 4574.565416285448, 3971.0, 3971.3050216212046, 3996.4005399189773, 4079.73481723687, 4116.547179810218, 5531.0873705838085, 5548.250986116823, 5553.139263414178, 5556.749946252428, 5580.107182850725, 5841.7371218295275, 5865.853353666193, 5867.853303667357, 5877.52979761618, 5907.1434641976675, 5441.945580544144, 5516.475934786189, 5552.194931734956, 5562.3881373048935, 5578.804741834017, 5009.799608015638, 5018.4981501849525, 5042.445331013586, 5051.87370315745, 5051.974740126276, 6127.135312147439, 6127.34224287047, 6127.34224287047, 6138.32709113608, 6139.584134579375, 5719.256496655408, 5779.884402311846, 5837.270341207349, 5857.033589457862, 5914.58597898143, 6122.265328160649, 6125.387461253839, 6128.938710613015, 6136.171616831671, 6137.171481849893, 5515.917261241165, 5711.3431955324, 5727.7422515986045, 5735.799247026501, 5739.339975902658, 5686.60193786431, 5708.5147762439, 5750.827475175752, 5805.767769289181, 5809.186713860147, 5641.041023026013, 5666.9716651416475, 5676.347220069745, 5676.460736230051, 5679.148127780701, 5550.4449555044175, 5644.407337229532, 5656.745446454837, 5666.263385759825, 5669.489745922887, 5607.635503692247, 5671.829845104653, 5674.602777805679, 5675.035244008446, 5722.570807189394, 5794.072948328248, 5808.448197421239, 5809.099589563624, 5821.679807610622, 5831.096180092092, 5529.834104976857, 5544.750486228048, 5583.860403489945, 5682.659040457585, 5707.971460142673], "value": 5412.175410818346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.8149214489579, 652.2433976587158, 653.542520235835, 656.960582365058, 666.1340257665034, 32621.51067733983, 35975.460368094464, 46600.76699616497, 47977.28034079492, 48147.759261203646, 49400.25899611509, 49669.50330496694, 49731.254031189565, 49734.253986190146, 49820.25269620959, 9043.457392556435, 9171.770705732377, 9185.311101667368, 9186.173244408003, 9186.86219706705, 8281.792955176139, 10731.78536429275, 10973.177011724152, 10999.505022273977, 11013.889861101366, 10792.298500597437, 10902.345859248471, 10916.962888525577, 11247.83128253077, 11251.324920504796, 8261.297789687824, 8463.4075614707, 11028.0, 11097.38964356956, 11119.610813621484, 8512.404131710782, 10731.624393146281, 11076.612318568812, 11076.778464430667, 11461.828072578917, 46607.53392466066, 46991.0, 47031.29453058207, 47032.5296747035, 47095.5290447098, 3675.981620091909, 3808.200277941608, 3939.980300098481, 4093.1199792044854, 4429.886597682299, 36173.81913090444, 36401.27197456062, 36483.81758091219, 36692.0, 36885.0, 4741.620670346364, 4753.595944344735, 4833.1058754130245, 4860.975695121537, 4934.629902757271, 403629.0, 404829.0, 405126.0, 405968.0, 407107.0, 71844.0, 72742.63628681928, 72857.2714272861, 72930.27069729341, 73083.63458182674, 38636.6136338633, 39477.60522394797, 39534.0, 41170.794146029075, 76036.23963760403, 16589.91705041491, 16624.6675066498, 16681.416150434678, 16693.749593756103, 16697.916510417614, 12653.74692506163, 13231.66920826987, 13442.865571344357, 13540.526081587097, 13592.0, 22526.436839079153, 22625.54748905013, 22648.433789155402, 22652.660210096863, 22687.205947791746, 7943.44395892282, 8111.716089936942, 8221.54781487012, 8356.373272004625, 8464.40749147566, 995.1690338567378, 1082.577794660083, 1087.3638921231168, 1114.6878873915316, 1182.0897908610323, 34261.0, 35133.82433087818, 35142.0, 35264.0, 35572.46641300383, 1122.893325134111, 1150.1603829204728, 1189.958351457712, 1193.612076075279, 1200.2618389690278, 18440.0, 18476.168572414274, 18671.439856804318, 18683.90658046701, 18711.906440467712, 598780.0, 598879.0, 599168.0, 599869.0, 605810.0, 67781.66109169522, 68482.3151768476, 68596.6570167146, 68677.0, 68866.311336887, 1370.842353129403, 1487.2935355705954, 1512.4026009726097, 1515.7574788034083, 1528.9006214596016, 34612.30775384428, 35316.64683353133, 35529.0, 35977.0, 36754.448683270304, 57146.714266428404, 57613.0, 57654.13518797305, 57791.55521112006, 57832.71083644555, 430098.0, 431155.0, 431350.0, 431354.0, 434370.0, 28373.716262837934, 29558.40883182367, 31657.36685266236, 31663.050108496784, 33040.50439243365, 53970.19044714254, 54402.18396724131, 54875.17687234615, 55391.16913246224, 55624.44375556193, 44797.0, 45084.54915450935, 45294.77352613216, 45656.60399976036, 46237.53762462467, 44131.33802993022, 44451.55548444474, 45079.32381014222, 45645.771771140935, 45894.0, 44483.332750009424, 45386.77306613446, 45400.77299613481, 45501.0, 45518.08963820771, 37510.8124459376, 39652.80173599113, 39965.0, 40546.39180412355, 40931.97670058272, 30128.69871301259, 30324.84837575798, 30499.695003050572, 30500.84749576238, 30530.694693052785], "value": 22081.943139625495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.31266846361183, 433.7267521461479, 435.2752666809762, 437.2697595016322, 443.323931005217, 19713.704294435578, 19825.107870145843, 20335.389938301876, 20357.592848143027, 20599.07304171312, 16755.748663770053, 18441.446756597292, 19171.808281917176, 19708.0, 19781.307654232103, 5764.394738552454, 6013.308469525995, 6016.608920420181, 6018.307894592134, 6019.518438524908, 4357.673174511909, 4428.0701052778795, 5870.0314448116005, 5872.17789509469, 6511.023346498016, 6579.703913323912, 6598.340165983412, 6604.3725846044545, 6611.570247933869, 6614.040964060232, 6478.74085036598, 6518.380753828379, 6557.049227861981, 6576.0793488911595, 6593.3736295051895, 3907.531096268466, 5480.534154596864, 5828.446297601721, 5861.003629382973, 5921.615095018853, 22613.54772904549, 24441.755582444304, 24485.0, 24789.5042099157, 24880.25359239226, 2564.6537717408096, 2634.9473010539873, 2754.73829986151, 2791.8464484453348, 2829.7028811974856, 15543.766843497357, 16236.75644865328, 16401.917990409973, 16494.42269520561, 16813.663726725397, 2057.5884823035453, 2147.8174355179826, 2148.301801914384, 2234.7094877665963, 2302.2172461363048, 70296.74762207031, 90097.0, 90793.54603227008, 96771.51614241884, 107321.46339268332, 17966.91016544935, 18391.81608183928, 18625.254989800254, 18722.344717934808, 19010.904945475184, 34877.30245395078, 34898.127546811535, 35256.82371588177, 35372.469412958315, 35612.46581301282, 9656.163174093219, 9901.801963960681, 9975.950120249498, 10070.848937265946, 10146.746331341628, 8338.874916876252, 8519.446235994641, 8572.571371431404, 8587.540118179924, 8879.378443509022, 9246.95376523113, 9428.292878034175, 9434.49048152306, 9499.287553433382, 9555.569999350046, 3437.016326326836, 3623.0942264433893, 3672.182269776478, 3751.390558667864, 3760.8683696070507, 954.2223088183108, 1003.287665757306, 1003.7942221844504, 1072.1369297715257, 1113.582406597526, 18842.81157188438, 19290.22839086449, 19502.0, 19565.0, 19896.303629372902, 1037.113268155731, 1044.5769463367349, 1082.5940272397852, 1082.9187810914214, 1111.666500049998, 10509.737256568647, 10757.515911783988, 10817.0, 10959.397233152253, 10986.615468458725, 393681.0, 395238.0, 399653.0, 533874.0, 535533.0, 86041.56979215064, 99850.5007474958, 101650.0, 102230.0, 103137.48431257946, 514.0849288266891, 532.1884126706803, 536.7960175133425, 537.8951104534657, 538.7225578826907, 11171.888281117084, 11662.416879155839, 12100.515979360727, 12170.45232964501, 12310.0, 42162.78918605387, 42629.0, 42811.7859410701, 43991.56008439875, 44429.777851110535, 52357.0, 53354.1996870055, 56132.71933640305, 61321.0, 62571.687141565824, 12753.234805911676, 13631.727365452438, 13638.318084095557, 13689.520866769615, 13717.45130194781, 13742.725145497234, 13918.304084795518, 14259.144451332953, 14296.356663949939, 14989.925050374679, 12169.20900141488, 12432.254064756144, 13006.739865202831, 13121.671958201121, 13182.538611148564, 12406.75811241659, 12804.10371274002, 12863.549775757803, 12930.353482326034, 13241.404136814059, 12153.1492795507, 12507.0, 12514.18657787241, 12565.748685026065, 12750.298733569743, 12990.805137922749, 13025.153365031243, 13249.668758281121, 13253.403596838358, 13496.797548036984, 12330.075244356529, 12453.377331133588, 12826.102172847812, 12874.098813082994, 12942.482300707867], "value": 10477.0787738727, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.6045327081783, 1575.2989919485826, 1616.2726772952171, 1799.1723807048756, 1915.2434788258638, 9477.668281610146, 9886.110250077492, 9913.15738162257, 10226.0, 10228.335158214712, 9903.356281841676, 10070.345427547223, 10096.343737657047, 10099.141572966306, 10099.343542669722, 2533.6832895888015, 2562.423454722691, 2563.51293254282, 2566.0120853471453, 2567.229831050677, 2536.315194897386, 2563.9230823075336, 2564.1153801938303, 2564.807639427041, 2565.7049439314533, 2483.279848843842, 2512.4095837478176, 2534.353739796349, 2539.263613552068, 2541.313845261778, 2420.9031638734427, 2454.1655837015323, 2458.5082983403213, 2482.739312372202, 2501.399664080626, 2458.385403649088, 2464.7904928081134, 2467.864267465289, 2478.491909158618, 2531.392465808211, 9898.208143348515, 9942.453165075918, 10035.09684128432, 10045.598176072948, 10176.287659863812, 5795.217645617872, 5930.0, 5933.495652869511, 5939.732712027969, 5942.257217847769, 3913.4716813230134, 3956.287868183726, 3961.9405708914387, 3996.48045754053, 4006.639402453793, 1252.7932891072962, 1255.2593969558004, 1260.2249616485888, 1267.4359909840125, 1300.8438987321488, 14313.42746290147, 14491.565253042425, 14582.562523124217, 14588.270586470633, 14589.6352591185, 5035.219540971156, 5092.898142037213, 5151.407588127338, 5164.9741751291, 5194.92207616886, 11096.056835169102, 11133.832992505118, 11134.27627204229, 11139.053180479588, 11146.609868654721, 5741.913871291934, 5766.308043034863, 5790.10253410722, 5793.102069179284, 5796.3913789052385, 4629.120467111238, 4872.171730805771, 5133.563647090039, 5192.065428222956, 5302.761375738101, 4202.6847986400535, 4203.306454435045, 4230.302000169938, 4266.338717498793, 4314.180305741899, 1236.4683186230006, 1276.2533917658275, 1293.5407930184838, 1295.8768916952874, 1300.3823183987531, 839.3956351426963, 882.2280504558511, 928.8885333759993, 1070.817960946641, 1077.4343469678324, 6466.741330346734, 6640.701168447432, 6758.526903116833, 6780.423663988518, 6888.965555172293, 532.0237364436302, 536.4742552298691, 555.7110302642603, 560.557159843727, 710.9797440672651, 4844.249141383091, 4906.9263961040615, 4946.579540739005, 4949.57928576075, 5021.723805190676, 12823.615291541082, 13107.737845243042, 13186.076974611877, 13250.536231232054, 13292.867071329356, 12104.0, 12330.445129969174, 12349.19730217533, 13067.411966461532, 13123.0, 9248.676296329597, 9388.483633400092, 9415.576299066559, 9426.198773104363, 9480.620775169054, 6526.836829079311, 6681.799546013627, 6759.357861003196, 6821.727130914708, 6849.48628852848, 11516.769664606829, 11717.238379505305, 11890.108241882066, 12021.579244726392, 12036.819447708116, 11759.353235572125, 11908.821367679318, 11947.984421323938, 11999.700007499534, 12292.692682683004, 8840.0, 8843.07147749477, 8982.101789821227, 9096.226820720049, 9126.771830704285, 9825.017498250118, 9846.113849753556, 9882.25883058759, 9909.504524773876, 9910.35582687123, 8512.319014478702, 8700.564971751513, 8735.126487351214, 8765.956170219108, 9004.63981440735, 8462.280706140047, 8497.320214382959, 8509.787255318666, 8580.399372043898, 8685.522296273766, 7838.568878711498, 7949.562774047483, 8019.278264956182, 8023.679052838054, 8034.076081250731, 5617.101263797773, 5679.943200567942, 5741.311042674905, 5807.854803629774, 5865.0, 5531.363893152339, 5561.249231353877, 5581.386047534849, 5679.233303504139, 5832.3292821326095], "value": 5103.587518041995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.47685425570154, 360.9097725568608, 363.6999475432768, 367.8023807748702, 369.08467001835464, 5298.496642818926, 5395.514403703666, 5400.675959442437, 5509.705219273473, 5536.474034966677, 5393.59548033897, 5450.373207081177, 5450.892990995134, 5457.5633949284065, 5471.261379713737, 4234.491860976679, 4248.3627455881715, 4259.339802330644, 4287.6569874410125, 4306.35404689296, 4041.4544036554985, 4160.43834082398, 4264.33902745075, 4299.527052024275, 4310.52584215736, 4253.723507971988, 4255.851045213418, 4278.871633850989, 4287.0, 4291.334843099309, 4238.495334156376, 4268.103698223361, 4271.8718438446895, 4291.206126866539, 4326.502452217972, 3693.242885208526, 3809.504764380613, 3852.0, 3852.672522835562, 3856.1709232515145, 5164.302819119446, 5200.0, 5201.505856943584, 5233.842984710464, 5236.528712415901, 4838.782254798507, 4856.39295088114, 4866.707997520125, 4870.586000189988, 4872.220444728827, 5436.945630543723, 5611.961787069406, 5640.435956404368, 5657.1514272858985, 5659.849257282248, 4096.283150448689, 4099.098198396348, 4100.7334523255895, 4137.441445404862, 4141.751494910285, 5915.905557471881, 5928.229330187091, 5973.506623344164, 5975.342712301644, 5992.43071908168, 3985.880423587297, 4180.519240287345, 4184.581541845791, 4213.873583792491, 4235.661147108224, 4244.766537840448, 4313.223619748413, 4332.740035597812, 4336.848210312624, 4338.457692788402, 4913.041956818386, 4920.917398172396, 4930.679505832181, 4942.332785073969, 4974.676646017997, 5462.672239665632, 5620.803271885547, 5677.432256774372, 5756.5682573807135, 5814.0, 1360.9523666671619, 1435.4904009076836, 1468.3025562857558, 1472.2859413184597, 1474.3807600807686, 935.4667839331515, 942.688912658821, 948.8139825218477, 950.5437390052813, 966.1787480641539, 4892.828750993698, 4904.852854414374, 4945.208766597327, 4951.876203094951, 4952.727599981963, 5183.948160518422, 5195.493306940944, 5239.607029472805, 5259.421463638997, 5297.218960046007, 4813.181759100891, 4950.455449900508, 4954.804151322449, 4968.776405061781, 5035.521625445577, 2783.6520434945633, 2809.227462447843, 2835.6247220098235, 2841.94847906276, 2844.3173638326653, 5339.546138578265, 5398.217258497496, 5417.3770016447825, 5422.267993820863, 5449.438763781017, 5261.5264626183825, 5266.919566771104, 5535.806246781343, 5991.400859913973, 6006.339302676702, 6056.909146362808, 6094.481969032682, 6112.572119951561, 6124.203853499017, 6124.755009799558, 5652.2086907833755, 5893.882122357614, 5894.469497745224, 5907.2911250650195, 5921.674307912934, 5818.883622327445, 5837.912431313619, 5847.210626565359, 5866.3547009828035, 5884.941150588611, 6068.4841788448475, 6094.634321940698, 6124.969375153096, 6159.045347971071, 6165.938340616536, 4240.625249860088, 4321.330193819963, 4343.956560434355, 4358.891027724332, 4359.912801744011, 5711.0, 5811.476967072984, 5888.793892213924, 5932.436418540232, 5936.198613187337, 5773.595848290579, 5776.306843178845, 5781.68200748946, 5790.594658373873, 5802.245708057925, 5749.885002299847, 5753.769849205984, 5773.037167363117, 5799.159121927212, 5806.2451881255165, 5501.807436739695, 5641.658711634625, 5721.427857214246, 5736.885262294814, 5746.046344242977, 5786.594938354443, 5799.362070172193, 5801.564882633904, 5820.991757843537, 5846.473817356458, 5448.782048718007, 5714.685692286798, 5724.599278050497, 5747.8563035924435, 5761.452661997104], "value": 4313.3229551651275, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.4116974024095, 816.7672213419177, 894.6108442827368, 895.5656506594302, 900.3427497926509, 15393.615159621, 16648.001119932796, 16655.916720416397, 16667.583310417245, 16671.249793759278, 14064.367103480343, 14073.718525629482, 14105.506307279247, 14107.929460352683, 14111.012229143964, 722.6892436252397, 857.8370109679172, 887.525174031893, 892.6652505310509, 893.9776505587347, 896.6368620708623, 900.7027680865332, 901.4411065139623, 903.1104362203224, 903.439867282286, 940.5908429833011, 943.6225509796102, 945.7588314979671, 947.1286416496839, 947.606743201574, 744.1999850161071, 915.9771005724843, 930.8324501589711, 931.2689538712146, 936.4708939449189, 690.126989358462, 691.3984833195102, 691.6645426967916, 691.9031335613018, 692.1521136607665, 15166.924165379103, 15173.696526069527, 15254.618634534114, 15302.387904483807, 15305.540833775005, 14650.0, 14849.628759281104, 14908.850911490854, 15030.248487575578, 15288.159151246677, 12745.426455809511, 13301.600951971457, 13348.132371395828, 13401.061925665208, 13544.59366219015, 4660.86017419478, 4693.6010439112715, 4696.342512048316, 4742.0, 4812.7352995585225, 70419.29580704178, 73232.26767732308, 73816.0, 76703.6164819178, 83455.5827220866, 12674.176178548365, 12869.0, 12953.417096230693, 13081.0, 13277.136986095873, 23016.654750178426, 23068.884655576614, 23107.306780796604, 23110.0, 23132.53734925292, 9090.181883630505, 9139.360244782802, 9373.297002724823, 9469.10043545862, 9471.66849160276, 4817.349657796152, 5006.949930500721, 5009.24861270805, 5063.215201643751, 5171.3535808024, 3918.941215881764, 4025.376066709665, 4028.5165780106577, 4031.4355990161403, 4197.9370309445385, 725.2131437390385, 726.9854602907913, 733.5915043117228, 735.9963200183964, 743.7694314762441, 774.8140446292849, 788.2905385153293, 815.4006804998321, 823.8846561481397, 826.9379796515285, 10084.445355505517, 10189.796204075878, 10253.38479691206, 10265.794684106277, 10368.637097701545, 703.3067320044336, 807.6163822184533, 814.8899898513744, 835.4235577451539, 841.381584535366, 4342.652587793033, 4604.792784324646, 4643.791029403617, 4820.108279968147, 4846.151923413388, 79089.20910790819, 123216.0, 128946.0, 134765.3261733685, 137480.0, 84417.0, 86662.56668716617, 93565.53217234007, 111206.0, 114318.42840785743, 76688.2331176681, 85739.57130214309, 89734.55132724295, 90710.09289907017, 91169.0, 10284.432400776446, 10425.582976680847, 10669.839952400565, 10731.40977246259, 10763.569457221936, 38489.422658660704, 38621.80689096537, 48359.5164048355, 48742.26886596775, 54018.40322331077, 69586.0, 77330.22669773456, 78014.2198578007, 79295.0, 81017.0, 13264.86735132675, 13396.2632055234, 13423.597292080862, 13713.520026799015, 14056.367463463965, 15225.6193595161, 15235.04506559053, 15716.0, 15853.365865365717, 16128.838711612734, 13638.522651707142, 13978.0, 14149.0, 14381.856181438052, 14468.638284042983, 14546.781798273247, 15106.924465377602, 15160.696786064438, 15465.0, 15499.690006199588, 14192.574222773334, 14414.639634008814, 14449.349779259748, 14615.92692036533, 14622.415103395744, 11490.253133546294, 11702.648920532398, 11894.167408281683, 11972.64082077539, 12093.213941094153, 11966.64100076999, 12422.751544969231, 12541.0, 12670.683232919251, 12888.0], "value": 8075.954298744512, "unit": "score"}], "name": "dromaeo_css", "value": 7785.075443835218, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 109.0, 110.0, 109.0, 108.0, 108.0, 110.0, 112.0, 111.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 133.0, 182.0, 125.0, 168.0, 146.0, 126.0, 179.0, 123.0], "value": 146.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 166.0, 160.0, 159.0, 161.0, 160.0, 159.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 127.0, 75.0, 77.0, 76.0, 76.0, 101.0, 74.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 116.0, 84.0, 84.0, 84.0, 117.0, 84.0, 84.0, 128.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 147.0, 145.0, 146.0, 146.0, 146.0, 149.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 52.0, 53.0, 52.0, 53.0, 54.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 76.0, 78.0, 77.0, 77.0, 77.0, 105.0, 77.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 125.0, 119.0, 119.0, 118.0, 117.0, 128.0, 121.0, 137.0, 118.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 156.0, 158.0, 158.0, 156.0, 154.0, 160.0, 156.0, 154.0, 165.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 64.0, 61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1486.1000000000001, "alertThreshold": 2.0}]} 08:45:16 INFO - Return code: 0 08:45:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:45:16 ERROR - # TBPL SUCCESS # 08:45:16 INFO - Running post-action listener: _resource_record_post_action 08:45:16 INFO - ##### 08:45:16 INFO - ##### Finished run-tests step (success) 08:45:16 INFO - ##### 08:45:16 INFO - Running post-run listener: _resource_record_post_run 08:45:16 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 503574528; Read time: 16; Write time: 1003948 08:45:16 INFO - TinderboxPrint: CPU usage
13.2% 08:45:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:45:16 INFO - TinderboxPrint: I/O write bytes / time
503,574,528 / 1,003,948 08:45:16 INFO - TinderboxPrint: CPU idle
8,267.4 (86.8%) 08:45:16 INFO - TinderboxPrint: CPU user
1,198.7 (12.6%) 08:45:16 INFO - TinderboxPrint: Swap in / out
0 / 0 08:45:16 INFO - install - Wall time: 17s; CPU: 17.0%; Read bytes: 0; Write bytes: 252653568; Read time: 0; Write time: 620220 08:45:16 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250920960; Read time: 16; Write time: 383728 08:45:16 INFO - Running post-run listener: _upload_blobber_files 08:45:16 INFO - Blob upload gear active. 08:45:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:45:16 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 08:45:16 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'] 08:45:16 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 08:45:17 INFO - (blobuploader) - INFO - Open directory for files ... 08:45:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:45:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:45:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:28 INFO - (blobuploader) - INFO - Done attempting. 08:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:29 INFO - (blobuploader) - INFO - Done attempting. 08:45:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:45:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:45:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:30 INFO - (blobuploader) - INFO - Done attempting. 08:45:30 INFO - (blobuploader) - INFO - Iteration through files over. 08:45:30 INFO - Return code: 0 08:45:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:45:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:45:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb103612066c8f68f36613be17b718246cf68678e2dc9cefccf2108f97b5d9112d3864f873c3c3dc9a4a1e9d8644fc215f8a3452ef1830cb2071191d36c59110", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b44de6288149e9dbf106591fbe2e407b3cb5d8c10f180ae20365ce4fb70b13a7aa6027a5a0660b33cd01c1f3dcde597ff6ab08bfd7bded4dd3de35a5ffc9f968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5be827c1fc18eb563ee1b3347f3d2caa207453829ca988bf9ffa5b2c181f10ab591e23fc4e23db6113e5215b8ad61b67c7d8377f92fe721ea32ca0705fdbf5b1"} 08:45:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:45:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:45:30 INFO - Contents: 08:45:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb103612066c8f68f36613be17b718246cf68678e2dc9cefccf2108f97b5d9112d3864f873c3c3dc9a4a1e9d8644fc215f8a3452ef1830cb2071191d36c59110", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b44de6288149e9dbf106591fbe2e407b3cb5d8c10f180ae20365ce4fb70b13a7aa6027a5a0660b33cd01c1f3dcde597ff6ab08bfd7bded4dd3de35a5ffc9f968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5be827c1fc18eb563ee1b3347f3d2caa207453829ca988bf9ffa5b2c181f10ab591e23fc4e23db6113e5215b8ad61b67c7d8377f92fe721ea32ca0705fdbf5b1"} 08:45:30 INFO - Running post-run listener: copy_logs_to_upload_dir 08:45:30 INFO - Copying logs to upload dir... 08:45:30 INFO - mkdir: /builds/slave/test/build/upload/logs 08:45:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.581706 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-21 08:45:29.775726) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 08:45:29.779755) ========= 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=af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139 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/fb103612066c8f68f36613be17b718246cf68678e2dc9cefccf2108f97b5d9112d3864f873c3c3dc9a4a1e9d8644fc215f8a3452ef1830cb2071191d36c59110", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b44de6288149e9dbf106591fbe2e407b3cb5d8c10f180ae20365ce4fb70b13a7aa6027a5a0660b33cd01c1f3dcde597ff6ab08bfd7bded4dd3de35a5ffc9f968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5be827c1fc18eb563ee1b3347f3d2caa207453829ca988bf9ffa5b2c181f10ab591e23fc4e23db6113e5215b8ad61b67c7d8377f92fe721ea32ca0705fdbf5b1"} build_url:https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/RbXgFBWHR0-YzoV5GWrrbQ/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/fb103612066c8f68f36613be17b718246cf68678e2dc9cefccf2108f97b5d9112d3864f873c3c3dc9a4a1e9d8644fc215f8a3452ef1830cb2071191d36c59110", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b44de6288149e9dbf106591fbe2e407b3cb5d8c10f180ae20365ce4fb70b13a7aa6027a5a0660b33cd01c1f3dcde597ff6ab08bfd7bded4dd3de35a5ffc9f968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5be827c1fc18eb563ee1b3347f3d2caa207453829ca988bf9ffa5b2c181f10ab591e23fc4e23db6113e5215b8ad61b67c7d8377f92fe721ea32ca0705fdbf5b1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 08:45:29.805513) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:45:29.805982) ========= 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=af724c981e2a0f424dbabf99000001e7-1469114682.275436-426916139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:45:29.890293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 08:45:29.890635) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 08:45:29.890944) ========= ========= Total master_lag: 0.17 =========