builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1466458179.14 results: success (0) buildid: 20160620184817 builduid: 692d6887aa22433d8976d9306ab80b05 revision: e50976a962c4059618f0adcef93e49acf7264215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.141173) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.141828) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.142162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.172770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.173067) ========= 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=923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-20 14:29:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-06-20 14:29:40 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166141 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.352957) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.353325) ========= 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=923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023089 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.403348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:39.403968) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e50976a962c4059618f0adcef93e49acf7264215 --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 e50976a962c4059618f0adcef93e49acf7264215 --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=923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-20 14:29:40,287 truncating revision to first 12 chars 2016-06-20 14:29:40,287 Setting DEBUG logging. 2016-06-20 14:29:40,287 attempt 1/10 2016-06-20 14:29:40,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e50976a962c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-20 14:29:40,622 unpacking tar archive at: mozilla-aurora-e50976a962c4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:40.107005) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:40.107322) ========= script_repo_revision: e50976a962c4059618f0adcef93e49acf7264215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:40.107799) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:40.108134) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 14:29:40.118904) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-20 14:29:40.119213) ========= /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=923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:29:41 INFO - MultiFileLogger online at 20160620 14:29:41 in /builds/slave/test 14:29:41 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 14:29:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:29:41 INFO - {'append_to_log': False, 14:29:41 INFO - 'base_work_dir': '/builds/slave/test', 14:29:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:29:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:29:41 INFO - 'branch': 'Mozilla-Aurora', 14:29:41 INFO - 'buildbot_json_path': 'buildprops.json', 14:29:41 INFO - 'config_files': ('talos/linux_config.py',), 14:29:41 INFO - 'default_actions': ('clobber', 14:29:41 INFO - 'read-buildbot-config', 14:29:41 INFO - 'download-and-extract', 14:29:41 INFO - 'populate-webroot', 14:29:41 INFO - 'create-virtualenv', 14:29:41 INFO - 'install', 14:29:41 INFO - 'run-tests'), 14:29:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:29:41 INFO - 'download_minidump_stackwalk': True, 14:29:41 INFO - 'download_symbols': 'ondemand', 14:29:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:29:41 INFO - 'tooltool.py': '/tools/tooltool.py', 14:29:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:29:41 INFO - '/tools/misc-python/virtualenv.py')}, 14:29:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:29:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:29:41 INFO - 'installer_path': 'installer.exe', 14:29:41 INFO - 'log_level': 'info', 14:29:41 INFO - 'log_name': 'talos', 14:29:41 INFO - 'log_to_console': True, 14:29:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:29:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:29:41 INFO - 'opt_config_files': (), 14:29:41 INFO - 'pip_index': False, 14:29:41 INFO - 'sps_profile': False, 14:29:41 INFO - 'sps_profile_interval': 0, 14:29:41 INFO - 'suite': 'dromaeojs', 14:29:41 INFO - 'system_bits': '32', 14:29:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:29:41 INFO - 'title': 'talos-linux64-ix-007', 14:29:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:29:41 INFO - 'use_talos_json': True, 14:29:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:29:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:29:41 INFO - 'work_dir': 'build'} 14:29:41 INFO - ##### 14:29:41 INFO - ##### Running clobber step. 14:29:41 INFO - ##### 14:29:41 INFO - Running pre-action listener: _resource_record_pre_action 14:29:41 INFO - Running main action method: clobber 14:29:41 INFO - rmtree: /builds/slave/test/build 14:29:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:29:42 INFO - Running post-action listener: _resource_record_post_action 14:29:42 INFO - ##### 14:29:42 INFO - ##### Finished clobber step (success) 14:29:42 INFO - ##### 14:29:42 INFO - ##### 14:29:42 INFO - ##### Running read-buildbot-config step. 14:29:42 INFO - ##### 14:29:42 INFO - Running pre-action listener: _resource_record_pre_action 14:29:42 INFO - Running main action method: read_buildbot_config 14:29:42 INFO - Using buildbot properties: 14:29:42 INFO - { 14:29:42 INFO - "project": "", 14:29:42 INFO - "product": "firefox", 14:29:42 INFO - "script_repo_revision": "production", 14:29:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:29:42 INFO - "repository": "", 14:29:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:29:42 INFO - "buildid": "20160620184817", 14:29:42 INFO - "stage_platform": "linux64-pgo", 14:29:42 INFO - "basedir": "/builds/slave/test", 14:29:42 INFO - "nightly_build": "True", 14:29:42 INFO - "buildnumber": 569, 14:29:42 INFO - "slavename": "talos-linux64-ix-007", 14:29:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:29:42 INFO - "platform": "ubuntu64_hw", 14:29:42 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:29:42 INFO - "branch": "mozilla-aurora", 14:29:42 INFO - "repo_path": "releases/mozilla-aurora", 14:29:42 INFO - "revision": "e50976a962c4059618f0adcef93e49acf7264215", 14:29:42 INFO - "pgo_build": "True", 14:29:42 INFO - "builduid": "692d6887aa22433d8976d9306ab80b05", 14:29:42 INFO - "slavebuilddir": "test" 14:29:42 INFO - } 14:29:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 14:29:42 INFO - Running post-action listener: _resource_record_post_action 14:29:42 INFO - ##### 14:29:42 INFO - ##### Finished read-buildbot-config step (success) 14:29:42 INFO - ##### 14:29:42 INFO - ##### 14:29:42 INFO - ##### Running download-and-extract step. 14:29:42 INFO - ##### 14:29:42 INFO - Running pre-action listener: _resource_record_pre_action 14:29:42 INFO - Running main action method: download_and_extract 14:29:42 INFO - mkdir: /builds/slave/test/build/tests 14:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:42 INFO - https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:29:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 14:29:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 14:29:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:48 INFO - Downloaded 1361 bytes. 14:29:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 14:29:48 INFO - Using the following test package requirements: 14:29:48 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 14:29:48 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:29:48 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:29:48 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'jsshell-linux-x86_64.zip'], 14:29:48 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:29:48 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 14:29:48 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:29:48 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:29:48 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:29:48 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:29:48 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:29:48 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:29: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')]} 14:29:48 INFO - https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:29:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:29:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:29:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:53 INFO - Downloaded 24472795 bytes. 14:29: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'] 14:29: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 14:29:54 INFO - Return code: 0 14:29:54 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 14:29:54 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')]} 14:29:54 INFO - https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:29:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:29:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:29:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:55 INFO - Downloaded 24472795 bytes. 14:29:55 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'] 14:29:55 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 14:29:57 INFO - Return code: 0 14:29:57 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')]} 14:29:57 INFO - https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:29:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 14:29:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 14:29:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:58 INFO - Downloaded 10833369 bytes. 14:29:58 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'] 14:29:58 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 14:29:58 INFO - Return code: 0 14:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:58 INFO - https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:29:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:29:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:29:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N2zcwoffSDWCaC34sUQSZw/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 14:29:59 INFO - Downloaded 63556516 bytes. 14:29:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:29:59 INFO - mkdir: /builds/slave/test/properties 14:29:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:59 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:59 INFO - Contents: 14:29:59 INFO - build_url:https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:30:00 INFO - Running post-action listener: _resource_record_post_action 14:30:00 INFO - Running post-action listener: set_extra_try_arguments 14:30:00 INFO - ##### 14:30:00 INFO - ##### Finished download-and-extract step (success) 14:30:00 INFO - ##### 14:30:00 INFO - ##### 14:30:00 INFO - ##### Running populate-webroot step. 14:30:00 INFO - ##### 14:30:00 INFO - Running pre-action listener: _resource_record_pre_action 14:30:00 INFO - Running main action method: populate_webroot 14:30:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:30:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:30:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:30:00 INFO - '--tppagecycles', 14:30:00 INFO - '7'], 14:30:00 INFO - 'tests': ['tsvgm']}}, 14:30:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:30:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:30:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:30:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:30:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['damp', 'tps']}, 14:30:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'tests': ['damp', 'tps']}, 14:30:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['dromaeo_dom']}, 14:30:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:30:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other-e10s': {'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tsvgx', 14:30:00 INFO - 'tsvgr_opacity', 14:30:00 INFO - 'tart', 14:30:00 INFO - 'tscrollx', 14:30:00 INFO - 'cart']}, 14:30:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:30:00 INFO - 'tsvgr_opacity', 14:30:00 INFO - 'tart', 14:30:00 INFO - 'tscrollx', 14:30:00 INFO - 'cart']}, 14:30:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tp5o']}, 14:30:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'tests': ['tp5o']}, 14:30:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s', 14:30:00 INFO - '--xperf_path', 14:30:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:00 INFO - 'tests': ['tp5n']}, 14:30:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:00 INFO - 'talos_options': ['--xperf_path', 14:30:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:00 INFO - 'tests': ['tp5n']}}, 14:30:00 INFO - 'talos.zip': {'path': '', 14:30:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:30:00 INFO - Running post-action listener: _resource_record_post_action 14:30:00 INFO - ##### 14:30:00 INFO - ##### Finished populate-webroot step (success) 14:30:00 INFO - ##### 14:30:00 INFO - ##### 14:30:00 INFO - ##### Running create-virtualenv step. 14:30:00 INFO - ##### 14:30:00 INFO - Running pre-action listener: _resource_record_pre_action 14:30:00 INFO - Running main action method: create_virtualenv 14:30:00 INFO - Creating virtualenv /builds/slave/test/build/venv 14:30:00 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 14:30:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:30:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:30:00 INFO - Using real prefix '/usr' 14:30:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:30:01 INFO - Installing distribute.............................................................................................................................................................................................done. 14:30:03 INFO - Installing pip.................done. 14:30:03 INFO - Return code: 0 14:30:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30: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', 'pip>=1.5'] in /builds/slave/test/build 14:30: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 pip>=1.5 14:30:03 INFO - Using env: {'DISPLAY': ':0', 14:30:03 INFO - 'HOME': '/home/cltbld', 14:30:03 INFO - 'LANG': 'en_US.UTF-8', 14:30:03 INFO - 'LANGUAGE': 'en_US:en', 14:30:03 INFO - 'LOGNAME': 'cltbld', 14:30:03 INFO - 'MAIL': '/var/mail/cltbld', 14:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:03 INFO - 'MOZ_NO_REMOTE': '1', 14:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:03 INFO - 'NO_EM_RESTART': '1', 14:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:03 INFO - 'PWD': '/builds/slave/test', 14:30:03 INFO - 'SHELL': '/bin/bash', 14:30:03 INFO - 'SHLVL': '1', 14:30:03 INFO - 'TERM': 'linux', 14:30:03 INFO - 'TMOUT': '86400', 14:30:03 INFO - 'USER': 'cltbld', 14:30:03 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:03 INFO - '_': '/tools/buildbot/bin/python'} 14:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:03 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 14:30:03 INFO - Cleaning up... 14:30:03 INFO - Return code: 0 14:30:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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', '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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:30: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 psutil>=3.1.1 14:30:03 INFO - Using env: {'DISPLAY': ':0', 14:30:03 INFO - 'HOME': '/home/cltbld', 14:30:03 INFO - 'LANG': 'en_US.UTF-8', 14:30:03 INFO - 'LANGUAGE': 'en_US:en', 14:30:03 INFO - 'LOGNAME': 'cltbld', 14:30:03 INFO - 'MAIL': '/var/mail/cltbld', 14:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:03 INFO - 'MOZ_NO_REMOTE': '1', 14:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:03 INFO - 'NO_EM_RESTART': '1', 14:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:03 INFO - 'PWD': '/builds/slave/test', 14:30:03 INFO - 'SHELL': '/bin/bash', 14:30:03 INFO - 'SHLVL': '1', 14:30:03 INFO - 'TERM': 'linux', 14:30:03 INFO - 'TMOUT': '86400', 14:30:03 INFO - 'USER': 'cltbld', 14:30:03 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:03 INFO - '_': '/tools/buildbot/bin/python'} 14:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:03 INFO - Downloading/unpacking psutil>=3.1.1 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:30:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:05 INFO - Installing collected packages: psutil 14:30:05 INFO - Running setup.py install for psutil 14:30:05 INFO - building 'psutil._psutil_linux' extension 14:30:05 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 14:30:05 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 14:30:05 INFO - building 'psutil._psutil_posix' extension 14:30:05 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 14:30:05 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 14:30:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:06 INFO - Successfully installed psutil 14:30:06 INFO - Cleaning up... 14:30:06 INFO - Return code: 0 14:30:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:30: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 mozsystemmonitor==0.3 14:30:06 INFO - Using env: {'DISPLAY': ':0', 14:30:06 INFO - 'HOME': '/home/cltbld', 14:30:06 INFO - 'LANG': 'en_US.UTF-8', 14:30:06 INFO - 'LANGUAGE': 'en_US:en', 14:30:06 INFO - 'LOGNAME': 'cltbld', 14:30:06 INFO - 'MAIL': '/var/mail/cltbld', 14:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:06 INFO - 'MOZ_NO_REMOTE': '1', 14:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:06 INFO - 'NO_EM_RESTART': '1', 14:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:06 INFO - 'PWD': '/builds/slave/test', 14:30:06 INFO - 'SHELL': '/bin/bash', 14:30:06 INFO - 'SHLVL': '1', 14:30:06 INFO - 'TERM': 'linux', 14:30:06 INFO - 'TMOUT': '86400', 14:30:06 INFO - 'USER': 'cltbld', 14:30:06 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:06 INFO - '_': '/tools/buildbot/bin/python'} 14:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:30:08 INFO - Installing collected packages: mozsystemmonitor 14:30:08 INFO - Running setup.py install for mozsystemmonitor 14:30:08 INFO - Successfully installed mozsystemmonitor 14:30:08 INFO - Cleaning up... 14:30:08 INFO - Return code: 0 14:30:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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', '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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:30: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 jsonschema==2.5.1 14:30:08 INFO - Using env: {'DISPLAY': ':0', 14:30:08 INFO - 'HOME': '/home/cltbld', 14:30:08 INFO - 'LANG': 'en_US.UTF-8', 14:30:08 INFO - 'LANGUAGE': 'en_US:en', 14:30:08 INFO - 'LOGNAME': 'cltbld', 14:30:08 INFO - 'MAIL': '/var/mail/cltbld', 14:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:08 INFO - 'MOZ_NO_REMOTE': '1', 14:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:08 INFO - 'NO_EM_RESTART': '1', 14:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:08 INFO - 'PWD': '/builds/slave/test', 14:30:08 INFO - 'SHELL': '/bin/bash', 14:30:08 INFO - 'SHLVL': '1', 14:30:08 INFO - 'TERM': 'linux', 14:30:08 INFO - 'TMOUT': '86400', 14:30:08 INFO - 'USER': 'cltbld', 14:30:08 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:08 INFO - '_': '/tools/buildbot/bin/python'} 14:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:08 INFO - Downloading/unpacking jsonschema==2.5.1 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:30:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:30:10 INFO - warning: no files found matching '*.txt' 14:30:10 INFO - no previously-included directories found matching 'build' 14:30:10 INFO - no previously-included directories found matching 'dist' 14:30:10 INFO - no previously-included directories found matching '.git*' 14:30:10 INFO - Installing collected packages: jsonschema, functools32 14:30:10 INFO - Running setup.py install for functools32 14:30:10 INFO - warning: no files found matching '*.txt' 14:30:10 INFO - no previously-included directories found matching 'build' 14:30:10 INFO - no previously-included directories found matching 'dist' 14:30:10 INFO - no previously-included directories found matching '.git*' 14:30:10 INFO - Successfully installed jsonschema functools32 14:30:10 INFO - Cleaning up... 14:30:10 INFO - Return code: 0 14:30:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:30:10 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')]} 14:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:10 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')]} 14:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:10 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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30:10 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 14:30:10 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 14:30:10 INFO - Using env: {'DISPLAY': ':0', 14:30:10 INFO - 'HOME': '/home/cltbld', 14:30:10 INFO - 'LANG': 'en_US.UTF-8', 14:30:10 INFO - 'LANGUAGE': 'en_US:en', 14:30:10 INFO - 'LOGNAME': 'cltbld', 14:30:10 INFO - 'MAIL': '/var/mail/cltbld', 14:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:10 INFO - 'MOZ_NO_REMOTE': '1', 14:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:10 INFO - 'NO_EM_RESTART': '1', 14:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:10 INFO - 'PWD': '/builds/slave/test', 14:30:10 INFO - 'SHELL': '/bin/bash', 14:30:10 INFO - 'SHLVL': '1', 14:30:10 INFO - 'TERM': 'linux', 14:30:10 INFO - 'TMOUT': '86400', 14:30:10 INFO - 'USER': 'cltbld', 14:30:10 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:10 INFO - '_': '/tools/buildbot/bin/python'} 14:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:11 INFO - Downloading/unpacking blobuploader==1.2.4 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:13 INFO - Downloading blobuploader-1.2.4.tar.gz 14:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:30:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:30:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 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 14:30:13 INFO - Downloading docopt-0.6.1.tar.gz 14:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:30:13 INFO - Installing collected packages: blobuploader, requests, docopt 14:30:13 INFO - Running setup.py install for blobuploader 14:30:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:30:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:30:14 INFO - Running setup.py install for requests 14:30:14 INFO - Running setup.py install for docopt 14:30:14 INFO - Successfully installed blobuploader requests docopt 14:30:14 INFO - Cleaning up... 14:30:14 INFO - Return code: 0 14:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:14 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')]} 14:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:14 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')]} 14:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:14 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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30:14 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 14:30:14 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 14:30:14 INFO - Using env: {'DISPLAY': ':0', 14:30:14 INFO - 'HOME': '/home/cltbld', 14:30:14 INFO - 'LANG': 'en_US.UTF-8', 14:30:14 INFO - 'LANGUAGE': 'en_US:en', 14:30:14 INFO - 'LOGNAME': 'cltbld', 14:30:14 INFO - 'MAIL': '/var/mail/cltbld', 14:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:14 INFO - 'MOZ_NO_REMOTE': '1', 14:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:14 INFO - 'NO_EM_RESTART': '1', 14:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:14 INFO - 'PWD': '/builds/slave/test', 14:30:14 INFO - 'SHELL': '/bin/bash', 14:30:14 INFO - 'SHLVL': '1', 14:30:14 INFO - 'TERM': 'linux', 14:30:14 INFO - 'TMOUT': '86400', 14:30:14 INFO - 'USER': 'cltbld', 14:30:14 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:14 INFO - '_': '/tools/buildbot/bin/python'} 14:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:14 INFO - Running setup.py (path:/tmp/pip-LJG6AR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:14 INFO - Running setup.py (path:/tmp/pip-XBpCDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:14 INFO - Running setup.py (path:/tmp/pip-1CnKJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:14 INFO - Running setup.py (path:/tmp/pip-byYCsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:15 INFO - Running setup.py (path:/tmp/pip-HgPZr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:15 INFO - Running setup.py (path:/tmp/pip-_EIed3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:15 INFO - Running setup.py (path:/tmp/pip-wvnoRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:15 INFO - Running setup.py (path:/tmp/pip-IXmhKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:15 INFO - Running setup.py (path:/tmp/pip-SD7IrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:15 INFO - Running setup.py (path:/tmp/pip-RdXdbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:15 INFO - Running setup.py (path:/tmp/pip-hLMm1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:15 INFO - Running setup.py (path:/tmp/pip-ZM8l1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:15 INFO - Running setup.py (path:/tmp/pip-MIFozn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:15 INFO - Running setup.py (path:/tmp/pip-GLLncq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:15 INFO - Running setup.py (path:/tmp/pip-we7sRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:15 INFO - Running setup.py (path:/tmp/pip-t79MiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:15 INFO - Running setup.py (path:/tmp/pip-xDs3bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:30:15 INFO - Running setup.py install for manifestparser 14:30:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Running setup.py install for mozcrash 14:30:16 INFO - Running setup.py install for mozdebug 14:30:16 INFO - Running setup.py install for mozdevice 14:30:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Running setup.py install for mozfile 14:30:16 INFO - Running setup.py install for mozhttpd 14:30:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Running setup.py install for mozinfo 14:30:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Running setup.py install for mozInstall 14:30:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:30:16 INFO - Running setup.py install for mozleak 14:30:16 INFO - Running setup.py install for mozlog 14:30:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Running setup.py install for moznetwork 14:30:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Running setup.py install for mozprocess 14:30:17 INFO - Running setup.py install for mozprofile 14:30:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Running setup.py install for mozrunner 14:30:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Running setup.py install for mozscreenshot 14:30:17 INFO - Running setup.py install for moztest 14:30:17 INFO - Running setup.py install for mozversion 14:30:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:30:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:30:17 INFO - Cleaning up... 14:30:17 INFO - Return code: 0 14:30:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:17 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')]} 14:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:17 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')]} 14:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:17 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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30:17 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 14:30:17 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 14:30:17 INFO - Using env: {'DISPLAY': ':0', 14:30:17 INFO - 'HOME': '/home/cltbld', 14:30:17 INFO - 'LANG': 'en_US.UTF-8', 14:30:17 INFO - 'LANGUAGE': 'en_US:en', 14:30:17 INFO - 'LOGNAME': 'cltbld', 14:30:17 INFO - 'MAIL': '/var/mail/cltbld', 14:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:17 INFO - 'MOZ_NO_REMOTE': '1', 14:30:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:17 INFO - 'NO_EM_RESTART': '1', 14:30:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:17 INFO - 'PWD': '/builds/slave/test', 14:30:17 INFO - 'SHELL': '/bin/bash', 14:30:17 INFO - 'SHLVL': '1', 14:30:17 INFO - 'TERM': 'linux', 14:30:17 INFO - 'TMOUT': '86400', 14:30:17 INFO - 'USER': 'cltbld', 14:30:17 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:17 INFO - '_': '/tools/buildbot/bin/python'} 14:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:17 INFO - Running setup.py (path:/tmp/pip-9XIy5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:18 INFO - Running setup.py (path:/tmp/pip-OlhthQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:18 INFO - Running setup.py (path:/tmp/pip-IKZrM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:18 INFO - Running setup.py (path:/tmp/pip-tiqXR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:18 INFO - Running setup.py (path:/tmp/pip-neK5IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:18 INFO - Running setup.py (path:/tmp/pip-R6yGv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:18 INFO - Running setup.py (path:/tmp/pip-vswU0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:18 INFO - Running setup.py (path:/tmp/pip-m8H_Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:18 INFO - Running setup.py (path:/tmp/pip-cnf2cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:18 INFO - Running setup.py (path:/tmp/pip-aO3Rwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:18 INFO - Running setup.py (path:/tmp/pip-Iv15TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:18 INFO - Running setup.py (path:/tmp/pip-LoBm2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:18 INFO - Running setup.py (path:/tmp/pip-X7xIgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:18 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)) 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:18 INFO - Running setup.py (path:/tmp/pip-ryC_C1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:19 INFO - Running setup.py (path:/tmp/pip-xlgnWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:19 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)) 14:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:19 INFO - Running setup.py (path:/tmp/pip-NxAb57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:19 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)) 14:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:19 INFO - Running setup.py (path:/tmp/pip-0r8kEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:19 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)) 14:30:19 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)) 14:30:19 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)) 14:30:19 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)) 14:30:19 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)) 14:30:19 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)) 14:30:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:21 INFO - Downloading blessings-1.6.tar.gz 14:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:30:21 INFO - Installing collected packages: blessings 14:30:21 INFO - Running setup.py install for blessings 14:30:21 INFO - Successfully installed blessings 14:30:21 INFO - Cleaning up... 14:30:21 INFO - Return code: 0 14:30:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:21 INFO - Reading from file tmpfile_stdout 14:30:21 INFO - Current package versions: 14:30:21 INFO - argparse == 1.2.1 14:30:21 INFO - blessings == 1.6 14:30:21 INFO - blobuploader == 1.2.4 14:30:21 INFO - docopt == 0.6.1 14:30:21 INFO - functools32 == 3.2.3-2 14:30:21 INFO - jsonschema == 2.5.1 14:30:21 INFO - manifestparser == 1.1 14:30:21 INFO - mozInstall == 1.12 14:30:21 INFO - mozcrash == 0.17 14:30:21 INFO - mozdebug == 0.1 14:30:21 INFO - mozdevice == 0.48 14:30:21 INFO - mozfile == 1.2 14:30:21 INFO - mozhttpd == 0.7 14:30:21 INFO - mozinfo == 0.9 14:30:21 INFO - mozleak == 0.1 14:30:21 INFO - mozlog == 3.2 14:30:21 INFO - moznetwork == 0.27 14:30:21 INFO - mozprocess == 0.22 14:30:21 INFO - mozprofile == 0.28 14:30:21 INFO - mozrunner == 6.11 14:30:21 INFO - mozscreenshot == 0.1 14:30:21 INFO - mozsystemmonitor == 0.3 14:30:21 INFO - moztest == 0.7 14:30:21 INFO - mozversion == 1.4 14:30:21 INFO - psutil == 3.1.1 14:30:21 INFO - requests == 1.2.3 14:30:21 INFO - wsgiref == 0.1.2 14:30:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:21 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')]} 14:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:21 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')]} 14:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:21 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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30:21 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 14:30:21 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 14:30:21 INFO - Using env: {'DISPLAY': ':0', 14:30:21 INFO - 'HOME': '/home/cltbld', 14:30:21 INFO - 'LANG': 'en_US.UTF-8', 14:30:21 INFO - 'LANGUAGE': 'en_US:en', 14:30:21 INFO - 'LOGNAME': 'cltbld', 14:30:21 INFO - 'MAIL': '/var/mail/cltbld', 14:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:21 INFO - 'MOZ_NO_REMOTE': '1', 14:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:21 INFO - 'NO_EM_RESTART': '1', 14:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:21 INFO - 'PWD': '/builds/slave/test', 14:30:21 INFO - 'SHELL': '/bin/bash', 14:30:21 INFO - 'SHLVL': '1', 14:30:21 INFO - 'TERM': 'linux', 14:30:21 INFO - 'TMOUT': '86400', 14:30:21 INFO - 'USER': 'cltbld', 14:30:21 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:21 INFO - '_': '/tools/buildbot/bin/python'} 14:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 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)) 14:30:21 INFO - Cleaning up... 14:30:21 INFO - Return code: 0 14:30:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:30:21 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')]} 14:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:21 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')]} 14:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:21 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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30:21 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 14:30:21 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 14:30:21 INFO - Using env: {'DISPLAY': ':0', 14:30:21 INFO - 'HOME': '/home/cltbld', 14:30:21 INFO - 'LANG': 'en_US.UTF-8', 14:30:21 INFO - 'LANGUAGE': 'en_US:en', 14:30:21 INFO - 'LOGNAME': 'cltbld', 14:30:21 INFO - 'MAIL': '/var/mail/cltbld', 14:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:21 INFO - 'MOZ_NO_REMOTE': '1', 14:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:21 INFO - 'NO_EM_RESTART': '1', 14:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:21 INFO - 'PWD': '/builds/slave/test', 14:30:21 INFO - 'SHELL': '/bin/bash', 14:30:21 INFO - 'SHLVL': '1', 14:30:21 INFO - 'TERM': 'linux', 14:30:21 INFO - 'TMOUT': '86400', 14:30:21 INFO - 'USER': 'cltbld', 14:30:21 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:21 INFO - '_': '/tools/buildbot/bin/python'} 14:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:30:22 INFO - Cleaning up... 14:30:22 INFO - Return code: 0 14:30:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:30:22 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')]} 14:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:22 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')]} 14:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:22 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 0x7f48b8ce9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, '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': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', '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 14:30:22 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 14:30:22 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 14:30:22 INFO - Using env: {'DISPLAY': ':0', 14:30:22 INFO - 'HOME': '/home/cltbld', 14:30:22 INFO - 'LANG': 'en_US.UTF-8', 14:30:22 INFO - 'LANGUAGE': 'en_US:en', 14:30:22 INFO - 'LOGNAME': 'cltbld', 14:30:22 INFO - 'MAIL': '/var/mail/cltbld', 14:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:22 INFO - 'MOZ_NO_REMOTE': '1', 14:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:22 INFO - 'NO_EM_RESTART': '1', 14:30:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:22 INFO - 'PWD': '/builds/slave/test', 14:30:22 INFO - 'SHELL': '/bin/bash', 14:30:22 INFO - 'SHLVL': '1', 14:30:22 INFO - 'TERM': 'linux', 14:30:22 INFO - 'TMOUT': '86400', 14:30:22 INFO - 'USER': 'cltbld', 14:30:22 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:22 INFO - '_': '/tools/buildbot/bin/python'} 14:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:22 INFO - Downloading/unpacking flake8 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:30:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:30:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:30:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:30:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:30:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:30:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:30:24 INFO - no previously-included directories found matching 'docs/_build' 14:30:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:30:24 INFO - Running setup.py install for pep8 14:30:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:30:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:30:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:30:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:30:25 INFO - no previously-included directories found matching 'docs/_build' 14:30:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:30:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:30:25 INFO - Cleaning up... 14:30:25 INFO - Return code: 0 14:30:25 INFO - Running post-action listener: _resource_record_post_action 14:30:25 INFO - Running post-action listener: _start_resource_monitoring 14:30:25 INFO - Starting resource monitoring. 14:30:25 INFO - ##### 14:30:25 INFO - ##### Finished create-virtualenv step (success) 14:30:25 INFO - ##### 14:30:25 INFO - ##### 14:30:25 INFO - ##### Running install step. 14:30:25 INFO - ##### 14:30:25 INFO - Running pre-action listener: _resource_record_pre_action 14:30:25 INFO - Running main action method: install 14:30:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:25 INFO - Reading from file tmpfile_stdout 14:30:25 INFO - Detecting whether we're running mozinstall >=1.0... 14:30:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:30:25 INFO - Reading from file tmpfile_stdout 14:30:25 INFO - Output received: 14:30:25 INFO - Usage: mozinstall [options] installer 14:30:25 INFO - Options: 14:30:25 INFO - -h, --help show this help message and exit 14:30:25 INFO - -d DEST, --destination=DEST 14:30:25 INFO - Directory to install application into. [default: 14:30:25 INFO - "/builds/slave/test"] 14:30:25 INFO - --app=APP Application being installed. [default: firefox] 14:30:25 INFO - mkdir: /builds/slave/test/build/application 14:30:25 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'] 14:30:25 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 14:30:41 INFO - Reading from file tmpfile_stdout 14:30:41 INFO - Output received: 14:30:41 INFO - /builds/slave/test/build/application/firefox/firefox 14:30:41 INFO - Running post-action listener: _resource_record_post_action 14:30:41 INFO - ##### 14:30:41 INFO - ##### Finished install step (success) 14:30:41 INFO - ##### 14:30:41 INFO - ##### 14:30:41 INFO - ##### Running run-tests step. 14:30:41 INFO - ##### 14:30:41 INFO - Running pre-action listener: _resource_record_pre_action 14:30:41 INFO - Running main action method: run_tests 14:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:30:41 INFO - Python 2.7.3 14:30:41 INFO - Return code: 0 14:30:41 INFO - grabbing minidump binary from tooltool 14:30:41 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')]} 14:30:41 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 0x217c1c0>, '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 0x217d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217d550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:30:41 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 14:30:41 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 14:30:41 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 14:30:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:30:41 INFO - Return code: 0 14:30:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:30:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:30:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:30:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:30:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:30:42 INFO - Return code: 0 14:30:42 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/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 14:30:42 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/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 14:30:42 INFO - Using env: {'DISPLAY': ':0', 14:30:42 INFO - 'HOME': '/home/cltbld', 14:30:42 INFO - 'LANG': 'en_US.UTF-8', 14:30:42 INFO - 'LANGUAGE': 'en_US:en', 14:30:42 INFO - 'LOGNAME': 'cltbld', 14:30:42 INFO - 'MAIL': '/var/mail/cltbld', 14:30:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:30:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:42 INFO - 'MOZ_NO_REMOTE': '1', 14:30:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:42 INFO - 'NO_EM_RESTART': '1', 14:30:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:42 INFO - 'PWD': '/builds/slave/test', 14:30:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:30:42 INFO - 'SHELL': '/bin/bash', 14:30:42 INFO - 'SHLVL': '1', 14:30:42 INFO - 'TERM': 'linux', 14:30:42 INFO - 'TMOUT': '86400', 14:30:42 INFO - 'USER': 'cltbld', 14:30:42 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305', 14:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:42 INFO - '_': '/tools/buildbot/bin/python'} 14:30:42 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/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 14:30:42 INFO - mozversion application_buildid: 20160620184817 14:30:42 INFO - mozversion application_changeset: e50976a962c4059618f0adcef93e49acf7264215 14:30:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:30:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:30:42 INFO - mozversion application_name: Firefox 14:30:42 INFO - mozversion application_remotingname: firefox-dev 14:30:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:30:42 INFO - mozversion application_vendor: Mozilla 14:30:42 INFO - mozversion application_version: 49.0a2 14:30:42 INFO - mozversion platform_buildid: 20160620184817 14:30:42 INFO - mozversion platform_changeset: e50976a962c4059618f0adcef93e49acf7264215 14:30:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:30:42 INFO - mozversion platform_version: 49.0a2 14:30:42 INFO - using testdate: 1466458242 14:30:42 INFO - actual date: 1466458242 14:30:42 INFO - starting webserver on 'localhost:32940' 14:30:42 INFO - SUITE-START | Running 2 tests 14:30:42 INFO - TEST-START | dromaeo_css 14:30:42 INFO - operating with platform_type : linux_ 14:30:42 INFO - Initialising browser for dromaeo_css test... 14:30:42 INFO - TEST-INFO | started process 27481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsXURc6/profile http://localhost:32940/getInfo.html) 14:30:45 INFO - PROCESS | 27481 | __metrics Screen width/height:1600/1200 14:30:45 INFO - PROCESS | 27481 | colorDepth:24 14:30:45 INFO - PROCESS | 27481 | Browser inner width/height: 1024/697 14:30:45 INFO - PROCESS | 27481 | __metrics 14:30:46 ERROR - PROCESS | 27481 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:30:47 INFO - PROCESS | 27481 | console.error: PushService: 14:30:47 INFO - PROCESS | 27481 | stateChangeProcessEnqueue: Error transitioning state 14:30:47 INFO - PROCESS | 27481 | UnknownError 14:30:47 INFO - TEST-INFO | 27481: exit 0 14:30:47 INFO - Browser initialized. 14:30:47 INFO - Running cycle 1/1 for dromaeo_css test... 14:30:47 INFO - TEST-INFO | started process 27570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsXURc6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:30:59 INFO - PROCESS | 27570 | RSS: Main: 145780736 14:30:59 INFO - PROCESS | 27570 | 14:31:50 INFO - PROCESS | 27570 | 1466458310612 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] 14:33:59 INFO - PROCESS | 27570 | Cycle 1(1): loaded http://localhost:32940/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32940/tests/dromaeo/cssquery-ext.html) 14:34:00 INFO - PROCESS | 27570 | RSS: Main: 219500544 14:34:00 INFO - PROCESS | 27570 | 14:37:02 INFO - PROCESS | 27570 | Cycle 1(1): loaded http://localhost:32940/tests/dromaeo/cssquery-ext.html (next: http://localhost:32940/tests/dromaeo/cssquery-jquery.html) 14:37:03 INFO - PROCESS | 27570 | RSS: Main: 235532288 14:37:03 INFO - PROCESS | 27570 | 14:40:04 INFO - PROCESS | 27570 | Cycle 1(1): loaded http://localhost:32940/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32940/tests/dromaeo/cssquery-mootools.html) 14:40:05 INFO - PROCESS | 27570 | RSS: Main: 245338112 14:40:05 INFO - PROCESS | 27570 | 14:43:07 INFO - PROCESS | 27570 | Cycle 1(1): loaded http://localhost:32940/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32940/tests/dromaeo/cssquery-prototype.html) 14:43:07 INFO - PROCESS | 27570 | RSS: Main: 237182976 14:43:07 INFO - PROCESS | 27570 | 14:46:10 INFO - PROCESS | 27570 | Cycle 1(1): loaded http://localhost:32940/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32940/tests/dromaeo/cssquery-yui.html) 14:46:11 INFO - PROCESS | 27570 | RSS: Main: 209600512 14:46:11 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | Cycle 1(1): loaded http://localhost:32940/tests/dromaeo/cssquery-yui.html (next: http://localhost:32940/tests/dromaeo/cssquery-dojo.html) 14:49:13 INFO - PROCESS | 27570 | RSS: Main: 264925184 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | __start_tp_report 14:49:13 INFO - PROCESS | 27570 | _x_x_mozilla_page_load 14:49:13 INFO - PROCESS | 27570 | _x_x_mozilla_page_load_details 14:49:13 INFO - PROCESS | 27570 | |i|pagename|runs| 14:49:13 INFO - PROCESS | 27570 | |0;dojo.html;3392.6776956189165;3435.4159792835217;3435.415979283523;3458.56767904012;3472.6180120186773;5757.856053598657;5795.362510123893;5798.884022319551;5810.767569297223;5852.707364631773;5794.130880367947;5834.5040671542865;5837.708114594275;5844.269466316699;5849.473547380735;5046.788770695025;5146.716930568818;5227.764750586232;5256.080185967459;5337.359516857982;5169.224616307546;5227.163653815391;5247.422783493813;5297.920531192035;5378.0857254266675;5195.818146364878;5200.635955483118;5222.686638801665;5287.101192797234;5290.814821481249;5224.216367544898;5272.472752724716;5278.736063196863;5282.920756188661;5316.601254905898;4846.830360937351;5029.949700503022;5076.654686508078;5117.872053198663;5178.456262092502;5697.4587414195585;5714.7428365723645;5716.828495145152;5724.198612194296;5747.511461525775;6119.5176204203435;6133.080037994292;6169.7840575580085;6185.134081228632;6198.2871969723155;5993.820185394445;6240.219972503437;6245.56281060326;6257.499400047986;6263.185785847856;5694.544436445074;5733.426657334275;5757.453041961007;5830.7959221427045;5836.212111364954;6328.493720502349;6329.556931014877;6335.904961425582;6353.459955903708;6373;5814.186013958049;6021.157038014681;6095;6098.481629061491;6103.1455596216565;6129.389354848411;6223.813285601439;6225.782097626561;6228.688565571794;6236.095766113888;6147.66187859672;6189.84525386869;6217.347178546278;6218.968905155445;6226.750929962751;5895.528357731457;5952.851178720567;6135.294441139326;6166.044263139221;6214.192155019909;4706.364640773451;4744.95255047452;4751.4535828379485;4758.738269395217;4774.856754297377;4218.514870789837;4222.387753775747;4222.9577704223175;4231.640310573635;4238.660907127422;5649.745761440663;5779.219805326311;5874.50066744323;5979.19280897082;5987.9101813472835;6219.440250377488;6251.812445626639;6262.6868656566985;6264.718087686065;6273.12176295319;5904.675242861684;5921.289445266596;5945.583809133319;5947.226860508193;5962.37395073511;4849.175640141185;5019.648624596243;5075.847724568269;5078.771455284521;5138.229265610188;6348.079528468346;6365.586236894587;6385.169927909435;6386.010168423902;6389.424951754364;6139;6205;6205.131281620667;6213.440790328892;6214.378562143749;6182.412670796267;6196.225471816023;6230.6884655767935;6259.280182779039;6380.298167201697;6027.638341699512;6061.454469097803;6074.544409169242;6079.331273559993;6107.69461526913;6048.183495228088;6137.60105593135;6144.354842741626;6145;6160.16837726919;5968.582199246011;6017.819465416044;6032.215811944419;6077.908831367621;6105.267367915879;6032.75868965254;6034.064719968412;6039.547033972557;6049.243844519435;6061.393860613904;5779.450952159538;5843.152742852348;5952.613080149777;5954.791582294599;5964.522838173022;5903.26209223847;5975.671338076448;5985.820425387245;6095.152603231562;6102.084687296941;5973.104034394875;6016;6016.2479690038745;6026.397360263939;6031.728572214173 14:49:13 INFO - PROCESS | 27570 | |1;ext.html;631.8325643704419;636.7866764633848;644.6969924135656;653.7679123911012;660.1681880830158;47631;49446.75276623612;49637;52597.73701131499;52648.21027684588;50580.74709626456;51251.23123153156;51271;51370.22944655824;51449.22826157611;8623.094575069621;8848.690295839648;9001.23489503393;10801.40592267425;11084.722881927935;10907.672769816918;11047.281926674741;11053.778924421547;11060.944695276552;11061.834072488919;11110.833337499944;11180.329180249171;11184.552617895275;11187.776244475106;11198.608048718297;8523.360747943898;10877.782444351147;11171.720706982309;11174.888251117465;11534.711632209179;10947;10980.450977451095;11036.392998385167;11046.281991670516;11056.502457389437;46138.30792538081;46403.767981160214;46439.30341044887;46457.30314045292;46584;3618.203995121069;3653.06846754076;3691.9815400923094;3812.90467738306;4343.305071188626;34067.14832129217;34618.48072278918;34906.47640285398;34946.47580286298;35135.824320878484;4420;4636.9536304637195;4649.674522783406;4739.336492890998;4764.166270902613;403220;405627.9718601418;405805;406101;407063;38659;39773;66368.33631663719;66469;66702;41216;41400.585994140274;41579.792101039304;41607.1678566427;41618.58381416208;15807.762883556756;17753;18071.277148913898;18074.63850722978;18078.90960545189;15117.54647360581;15399.07605543671;15401.691966160613;15779.526614201592;15785.289661965238;9282.071792820663;9297.395669281475;9334.673286435078;9367.344285899922;9383.436993780395;3552.8223588820456;3562.358775420449;3593.975716920665;3606.880048757858;3634.454831775202;1116.609186784619;1121.5121422181378;1132.8923752243525;1214;1228.4717571444187;34061.659383406346;34734.47898281528;34786;34816.129596759776;35017.474737878954;1194.6834088966432;1201.0691713921606;1225.8774122587847;1248.375812093953;1281.39774306076;17852.642947141245;18090.728639070425;18129.36547220841;18181.45455636333;18225.08874556267;163645;165377;165572;165605;165870.170649146;64633;66103;66161.6691916547;66596.6670166646;66606.33393666099;1369.9520516781865;1419.9006069575237;1425.2446203512154;1478.9260536973109;1537.8231503377256;30393;35707.95887697898;36007.63992360148;36889.26221475502;37574.624253757116;63611.36388636239;64540.67729661322;64629;64640;64690.35309646843;528742;542895;545255;545365;546147;27757.30606734767;28596.571051433828;28649.28376790597;31092.68907310898;31618;45736.31395529136;52767.472325276256;58499.70750146222;58684.706576466844;59123.40876591179;45387;46208.76895615501;46384;46455.30317045179;47797.522024779304;44732.77633611811;45234.32148517709;45439.77280113578;45553.54446455493;46871.76564117294;45430.31854522251;46301.53698463107;47126;47869.76065119652;48447.78880528015;33944.32111357809;35985.28029439449;36136;36518.26963460663;37112.25775484529;28995.56506652444;29167.7083229165;29197.708022919498;29364.412711746074;29585.408291833613 14:49:13 INFO - PROCESS | 27570 | |2;jquery.html;432.1314158541331;434.04379278535714;435.82566973210714;437.9408779814728;441.7614488176385;19286.51783705408;19697.21211151552;20472.590548189026;20492.69260961083;20508.077136528856;19296.324628638;19653.803461965377;20244.39266821994;20758.896205518988;20801.375958721226;5919.23050003499;6018.969905150469;6095.939040609582;6110.694465276741;6133.1106989486725;6014.278286605613;6052.394760523956;6059.394060593927;6073.696315184223;6107.267127944653;5982.192404025466;6007.7596896124105;6023.789167379143;6024.096385542181;6035.577509574332;5766.769329226826;5985.371535988724;6066.696665166724;6072.696365181745;6174.765046990575;5711.485966263056;5964.463198312129;5971.761129554856;5978.073398623221;5993.220881285405;14164.858351416457;14643.780343294859;14805.111693298437;14805.259737013106;14857.554273371816;2323.848949818256;2326.953460930789;2368.206650771976;2386.296042667417;2411.3368823573655;10746.301490403099;11185.384803835786;11272.887271127265;11285.717857053558;14389.64025899361;1984.811442912921;2086.54096098858;2116.777738337468;2203.933881983543;2226.25420484138;89460.5526972361;90193.54903225442;90353.54823225907;90549.54725226332;92081;11449.141314401453;11738.767429419959;11943.76112477763;12112.09159313055;12241.510339586495;17016.659666806594;17302.22140003703;17317.91341043312;17325.306987720604;17329.56676083083;8865.911340886509;8877.822443551093;9077.591508382138;9094.181523662903;9144.817103658022;8818.77953051166;8942.910570894337;9088.772780680536;9168.404053736485;9225.769355766159;5009.423916249605;5106.259592359087;5234.476552344735;5249.763760630781;5302.337207849019;2091.926782562603;2103.8533998970597;2136.102836808544;2148.2481131604127;2158.8057074863336;989.5299732627088;1029.1046789293214;1085.8262677971486;1088.3089238333714;1155.5089087138101;19068.61862762737;20081.59836803256;20419;20461.283855065;20812.27157049496;1006.6527048168371;1022.3712416863724;1049.6273822792798;1098.2751384086625;1098.609993452313;10837.620683276047;11183.888161118448;11345.773084538263;11450.885491145149;11487.0810335173;504484;516730;519719;520860;522760;66873.66563167152;83073.75389369164;102829.48585257026;107874.46062769636;108447;1070.8982646648556;1090.487470888681;1114.9108071354271;1117.1063149480383;1117.1621284036971;18026.639467210844;18044;18151.364702235358;18324.54188645241;18474.168662409957;43576.782116090486;44286.33570496381;44399.778001109786;45274.54725452703;45631.77184114058;62830.37169628428;63184;63875.36124638694;64061;64471;8825.426347287406;8926.598303076247;8950.23922966555;9073.092690730875;9368.625254989996;10154.695359139238;12356.752864942471;13332.40004199794;13953.790693139408;14052.648683782987;12289.139760217056;12312.876871231172;12340.691482713004;12368.381580921097;12469.064820138345;12454.43955022008;12478.438470268678;12583.182093163916;13042.608721738363;13114.278714670785;12674.493020279086;13017.934910325388;13088.214707117604;13252.668683282995;13264.933675331562;9135.132162444557;9254.722358329262;9303.348765586345;9477.905220947978;9661.9033809661;9803.509824508603;12533.310667913353;12841;12846.807297890726;13292.468301267842 14:49:13 INFO - PROCESS | 27570 | |3;mootools.html;1248.9562865299715;1601.3034330066419;1629.3808352825924;1829.734688470172;1899.3637131560927;9001.324900632455;9424.905750942497;9436.245100391969;9716.465594392306;9733.707988760349;9224.584893679783;9476.526173691322;9525.047495250488;9538.093881081286;9574.186194173486;2441.7192022917325;2453.595156799135;2467.4201562632857;2472.320111969214;2473.4311108444977;2354.2937118864356;2535.5943049112147;2535.936601584966;2537.4417628121782;2537.6827896512937;2452.619843924195;2466.950660986779;2497.950040999179;2500.987495062522;2501.799856011524;2449.35092200567;2456.4104614892476;2459.4220358215807;2468.0374653885;2478.31846242283;2411.770881766229;2416.5046165536023;2442.6336049592524;2443.230382429529;2448.669429626995;9222.677206297747;9412.247020238365;9463.621455141785;9474.099960503741;9589;5639.379668236491;5765.048766953448;5770.105633626795;5775.335836378787;5777.220075289824;3430.5368775215525;3520.612732599412;3544.5392099027217;3563.6436356364416;3574.4638304254568;1159.7564511452606;1172;1189.9345535995517;1192.4931903940876;1197.239752756997;13423.597292081253;13509.189448633015;13525.256110913895;13557.864421355758;13576.93211533946;4650.697704649255;4721.1501929652995;4776.59398951093;4792.520747925249;4847.054824309296;10456;10458.581656733797;10483.580656773644;10492.4229167395;10505.264631475724;5567.860803479945;5583.804566840141;5612.017896868032;5613.7193140342815;5622.156676498558;4516.458025036951;4766.61867050642;4824.059308434892;5115.7953681852305;5228.712420816815;4230.640395566411;4257.723247988933;4293.828246870153;4330.631896288788;4358.65130789536;1258.8678188790045;1317.5059352742721;1317.5783749200164;1317.736452709462;1333.6265845563257;1006.094514936563;1009.7021378693303;1023.0281232828843;1042.7393151712072;1050.8633877595864;6087.817365479043;6159.19930409044;6321.54978644086;6351.841203969938;6363.331850155667;441.6180004296261;456.53661533542817;480.5434836904954;485.90039041996306;490.91654418748897;4155.625993660585;4262.552431994658;4285.957140428556;4308.89227769302;4343.153085148366;11963.64109076729;12338.938305308417;12345.444454999546;12392.194507356993;12400.441980110916;11820.527178912918;12154.817677734842;12229.205101668364;12321.383930803604;12325.50697972089;8621.09478504748;8992.910070899206;8993.055729148346;9044.231240344512;9129.77175570616;6385.718992422289;6594.934050659432;6598.67006649656;6637.834054148491;6692.76575319881;10958.780824383308;11056.226064175431;11403.486843091914;11506.539738410369;11507.252028618113;11392.316461012366;11558.075353971493;11643.12676549279;11705.531778729097;11754.412279386168;7862.646180921986;8103.959480202561;8282.213189746964;8556.144385561394;8564.871526927225;9335.906640933776;9405.294602904673;9406.153446189875;9468.337216394786;9484.146426821619;8528.74413767588;8562.272206862486;8660.35047371437;8669.393142479967;8712.303015758851;8528.701495447629;8543.359248056297;8560.357973151915;8564.229219370038;8657.567121644019;7574.356179724565;7670.348020418104;7710.884336734841;7716.266954639525;7724.1117271514495;5394.946050539601;5411.594130440154;5482.890342193214;5582.3859375467855;5670.5180059695385;5300.1519756838725;5390.676559406448;5607.102863541977;5655.688937108334;5829.358770535153 14:49:13 INFO - PROCESS | 27570 | |4;prototype.html;338.5024014698393;346.7243541384599;351.91554027033504;367.1610370303856;370.4313878196968;5489.517830185848;5532.170174473831;5548.250986116873;5553.639013464122;5566.888662226758;5445.754941027653;5471.945280547194;5490.16079613329;5507.146392309189;5511.5039646431815;3998.740081894682;4148.191102734968;4376.1247750449875;4381.671374646899;4413.3159360299205;4332.111917057012;4337.956620433787;4340.110277393127;4358.171947330013;4369.737815731066;4291.334843099325;4320.82716691332;4331.740095594275;4334.916270932267;4349.847755328564;4148.846876373947;4185.769782661952;4291.356296555542;4327.3725309830215;4339.175556644243;3844.0774214188395;3845.6731177849915;3853.477876238898;3856.014152711356;3863.8454461821493;5259.815906443256;5267.815626453094;5313.548348390392;5352.892942141174;5357.687366595209;4906.803727850882;4947.876303092451;4948.906933166771;4959.851204463872;4975.527324904128;5798.768049278024;5799.21710569072;5807.2160258365;5812.128180772876;5815.447532484407;4050.9594904050878;4092.9590704092875;4100.446439730628;4229.175310814392;4235.851745188935;5959.225300710923;5963.731632076524;5972.372900845392;5983;6012.428819262163;4316.158349121891;4316.460442444694;4321.876312158798;4341.587549182823;4343.2399330117105;4068.450759147477;4327.052826228147;4327.913441731148;4328.502222244482;4343.630791382705;4898.191798353303;4906.55840974314;4927.753612319441;4944.170656856986;4948.4309304429735;5682.448469706434;5773.979107312407;5776.249087618583;5788.453080644503;5802.941970580325;1456.3592019511382;1488.776683497484;1489.5903626502582;1490.344248530643;1500.2873635023277;940.6660635474445;956.3257903178243;960.1550635440767;972.0668158567854;974.4348277998744;5040.765012571944;5050.974745126251;5061.493850614983;5145.3568303962;5146.305248791366;5255.503635773053;5275.868103297448;5299.6025298102795;5333.359996800409;5369.19462080691;4997.9504304096545;5008.574271186917;5011.949880501148;5278.683279003272;5733.942660573424;3145.8741650334186;3157.936841263162;3175.555422240888;3188.4101441233447;3192.8824911280867;6043.912255185238;6139.969300153471;6186.608013196995;6194.5973511721595;6202.937970620236;5922.496587790086;5985.491233245136;6021.367756385516;6048.667323297261;6083.300420451616;6122.112293717475;6131.662758548234;6147.969260153671;6165.167702360213;6225.439710426083;5511.779528818803;5787.450192231731;5891.1457838614015;5912.970435147797;6013.488853447506;5806.303243610793;5817.2146760188525;5818.418158184148;5827.912581311199;5859.00396932514;5901.3213480448585;6077.149199112217;6095.939040609537;6117.480014198843;6141.447269745744;4225.915481690287;4243.724157929724;4243.872683819491;4252.277112890878;4264.893377665583;5923.763049477972;5928.703564821828;5970.4178392726835;6005.1892994446935;6016.548758843017;5579.302587176603;5743.1385292206505;5838.795642152504;5881.73532191061;5900.822975310747;5864.149698293638;5874.236349274567;5876.911846322223;5903.350631430453;5922.496587789914;5730.914036289542;5771.538276937751;5773.162891380643;5785.884282314246;5789.478946894799;5682.494139053071;5829.766809327749;5882.470577648032;5894.882102358015;5932.597298094279;5658.405867383865;5716.199732037435;5761.617211869205;5762.6830524319885;5814.476697097281 14:49:13 INFO - PROCESS | 27570 | |5;yui.html;790.1584812175033;805.476440313796;811.4765975945519;890.7817584691752;937.4562753602911;14661.41354345825;15636.609084772857;15647.139407332592;15665.21673916304;15698.921505392456;14275.714485710305;14503.419863205485;14539.49111781088;14541.78187327191;14550.708985820278;871.4858233642166;888.4314039015036;891.6210610490548;896.9910300897005;897.9910200898005;888.1251966812684;889.8353804546148;891.6344298837478;891.7235656946367;892.2282225874604;939.6945992552413;943.9339246252731;945.1918609588806;945.3382632157483;945.4894357047186;932.6782260120248;932.9393589416666;936.2977766674994;940.041158018817;942.4910548303908;691.7094820175538;692.0968136581758;692.8648913461877;693.4071368979551;694.3056943056943;14148.151110933377;14267.928660356736;14304.785428218585;14307.78538321926;14331.355089021019;14167.14997100167;14245.78631320531;14342.78485822703;14457.349419276157;14663.193524356157;11760.941195293968;11953.22304050234;12250.75498490034;12344.444499997522;12639.747205055939;3994.6205110514456;4038.474998250238;4053.4730485037053;4107.424960505532;4115.36211887158;50240;53201;70269.94595081078;70528.29471705269;78824;12944.288064156563;13234.73530529384;13292.468301268034;13389.799153012713;13694.109882857583;24343.634845477332;24348.269551913472;24396;24410.51178976446;24472.755272447048;9436.339456237998;9605.087516685904;9685.515724213761;9758.365706229073;9853;4936.432310284292;5143.614228932845;5145.948540514547;5201.7919283229;5235.973820130951;3858.3440815060944;3938.4289278055094;3940.133170702441;3989.900252493711;3989.9601003989587;683.2859661653584;689.7206631314326;690.8203866994517;693.2409012131708;693.6774399904069;724.6376811594203;799.5322736199272;806.8103995561156;832.7959649885817;833.3832963606887;10625.787484250272;10838.891611083945;10965.396903170242;10986.176036797273;11023.503942322615;660.8942569189003;687.051868421575;724.6014691919529;770.2874840772375;805.399977017126;4389.626881715026;4474.664400170001;4563.748993805306;5293.311869456946;5415.1335786274;127974;129700;131290;132044;132183.3822286307;109794;110162;112104;112721;113990;69880;96450;96957;97743.51128244455;97784.51107744558;8935.374523783274;9131.634734610807;9206.723798286062;9227.123423274777;9273.814523709623;55052.97208653402;55166.448335517736;55263.44736552583;55875;56254.71872640611;80333;84628.79609138174;86507.5674621648;86997.56501217453;87457.56271218603;12675.17611355259;13164.60506184816;13165.341732913508;13238.669033274246;13338.466461341826;13536.729265414833;13683.794743079061;14493.565193044225;14777.482788102365;14869.405223790927;12990.480380784662;13664.726705466033;13775.793363099761;13776.10455320401;14102.576922692335;13449.932750336186;13540.458381664623;13555.322233888463;13636.454541818217;13824.37790299459;12104.273743575413;12198.32909190003;12567.622971310875;12571.497140114294;13325.267110308638;10268.691939241835;10385.117265032557;10547.841782373118;10655.786884262427;10928.672139835817;10271.126954208677;10741.355518668905;10783.352998820095;10787.892121078688;10815.188860835626 14:49:13 INFO - PROCESS | 27570 | __end_tp_report 14:49:13 INFO - PROCESS | 27570 | __start_cc_report 14:49:13 INFO - PROCESS | 27570 | _x_x_mozilla_cycle_collect,912 14:49:13 INFO - PROCESS | 27570 | __end_cc_report 14:49:13 INFO - PROCESS | 27570 | __startTimestamp1466459353809__endTimestamp 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | ------- Summary: start ------- 14:49:13 INFO - PROCESS | 27570 | Number of tests: 6 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | [#0] dojo.html Cycles:165 Average:5723.80 Median:5968.58 stddev:649.60 (10.9%) stddev-sans-first:625.32 14:49:13 INFO - PROCESS | 27570 | Values: 3392.7 3435.4 3435.4 3458.6 3472.6 5757.9 5795.4 5798.9 5810.8 5852.7 5794.1 5834.5 5837.7 5844.3 5849.5 5046.8 5146.7 5227.8 5256.1 5337.4 5169.2 5227.2 5247.4 5297.9 5378.1 5195.8 5200.6 5222.7 5287.1 5290.8 5224.2 5272.5 5278.7 5282.9 5316.6 4846.8 5029.9 5076.7 5117.9 5178.5 5697.5 5714.7 5716.8 5724.2 5747.5 6119.5 6133.1 6169.8 6185.1 6198.3 5993.8 6240.2 6245.6 6257.5 6263.2 5694.5 5733.4 5757.5 5830.8 5836.2 6328.5 6329.6 6335.9 6353.5 6373.0 5814.2 6021.2 6095.0 6098.5 6103.1 6129.4 6223.8 6225.8 6228.7 6236.1 6147.7 6189.8 6217.3 6219.0 6226.8 5895.5 5952.9 6135.3 6166.0 6214.2 4706.4 4745.0 4751.5 4758.7 4774.9 4218.5 4222.4 4223.0 4231.6 4238.7 5649.7 5779.2 5874.5 5979.2 5987.9 6219.4 6251.8 6262.7 6264.7 6273.1 5904.7 5921.3 5945.6 5947.2 5962.4 4849.2 5019.6 5075.8 5078.8 5138.2 6348.1 6365.6 6385.2 6386.0 6389.4 6139.0 6205.0 6205.1 6213.4 6214.4 6182.4 6196.2 6230.7 6259.3 6380.3 6027.6 6061.5 6074.5 6079.3 6107.7 6048.2 6137.6 6144.4 6145.0 6160.2 5968.6 6017.8 6032.2 6077.9 6105.3 6032.8 6034.1 6039.5 6049.2 6061.4 5779.5 5843.2 5952.6 5954.8 5964.5 5903.3 5975.7 5985.8 6095.2 6102.1 5973.1 6016.0 6016.2 6026.4 6031.7 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | [#1] ext.html Cycles:180 Average:55889.20 Median:20290.62 stddev:107197.20 (528.3%) stddev-sans-first:107417.63 14:49:13 INFO - PROCESS | 27570 | Values: 631.8 636.8 644.7 653.8 660.2 47631.0 49446.8 49637.0 52597.7 52648.2 50580.7 51251.2 51271.0 51370.2 51449.2 8623.1 8848.7 9001.2 10801.4 11084.7 10907.7 11047.3 11053.8 11060.9 11061.8 11110.8 11180.3 11184.6 11187.8 11198.6 8523.4 10877.8 11171.7 11174.9 11534.7 10947.0 10980.5 11036.4 11046.3 11056.5 46138.3 46403.8 46439.3 46457.3 46584.0 3618.2 3653.1 3692.0 3812.9 4343.3 34067.1 34618.5 34906.5 34946.5 35135.8 4420.0 4637.0 4649.7 4739.3 4764.2 403220.0 405628.0 405805.0 406101.0 407063.0 38659.0 39773.0 66368.3 66469.0 66702.0 41216.0 41400.6 41579.8 41607.2 41618.6 15807.8 17753.0 18071.3 18074.6 18078.9 15117.5 15399.1 15401.7 15779.5 15785.3 9282.1 9297.4 9334.7 9367.3 9383.4 3552.8 3562.4 3594.0 3606.9 3634.5 1116.6 1121.5 1132.9 1214.0 1228.5 34061.7 34734.5 34786.0 34816.1 35017.5 1194.7 1201.1 1225.9 1248.4 1281.4 17852.6 18090.7 18129.4 18181.5 18225.1 163645.0 165377.0 165572.0 165605.0 165870.2 64633.0 66103.0 66161.7 66596.7 66606.3 1370.0 1419.9 1425.2 1478.9 1537.8 30393.0 35708.0 36007.6 36889.3 37574.6 63611.4 64540.7 64629.0 64640.0 64690.4 528742.0 542895.0 545255.0 545365.0 546147.0 27757.3 28596.6 28649.3 31092.7 31618.0 45736.3 52767.5 58499.7 58684.7 59123.4 45387.0 46208.8 46384.0 46455.3 47797.5 44732.8 45234.3 45439.8 45553.5 46871.8 45430.3 46301.5 47126.0 47869.8 48447.8 33944.3 35985.3 36136.0 36518.3 37112.3 28995.6 29167.7 29197.7 29364.4 29585.4 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | [#2] jquery.html Cycles:180 Average:30561.28 Median:2126.44 stddev:85345.28 (4013.5%) stddev-sans-first:85554.71 14:49:13 INFO - PROCESS | 27570 | Values: 432.1 434.0 435.8 437.9 441.8 19286.5 19697.2 20472.6 20492.7 20508.1 19296.3 19653.8 20244.4 20758.9 20801.4 5919.2 6019.0 6095.9 6110.7 6133.1 6014.3 6052.4 6059.4 6073.7 6107.3 5982.2 6007.8 6023.8 6024.1 6035.6 5766.8 5985.4 6066.7 6072.7 6174.8 5711.5 5964.5 5971.8 5978.1 5993.2 14164.9 14643.8 14805.1 14805.3 14857.6 2323.8 2327.0 2368.2 2386.3 2411.3 10746.3 11185.4 11272.9 11285.7 14389.6 1984.8 2086.5 2116.8 2203.9 2226.3 89460.6 90193.5 90353.5 90549.5 92081.0 11449.1 11738.8 11943.8 12112.1 12241.5 17016.7 17302.2 17317.9 17325.3 17329.6 8865.9 8877.8 9077.6 9094.2 9144.8 8818.8 8942.9 9088.8 9168.4 9225.8 5009.4 5106.3 5234.5 5249.8 5302.3 2091.9 2103.9 2136.1 2148.2 2158.8 989.5 1029.1 1085.8 1088.3 1155.5 19068.6 20081.6 20419.0 20461.3 20812.3 1006.7 1022.4 1049.6 1098.3 1098.6 10837.6 11183.9 11345.8 11450.9 11487.1 504484.0 516730.0 519719.0 520860.0 522760.0 66873.7 83073.8 102829.5 107874.5 108447.0 1070.9 1090.5 1114.9 1117.1 1117.2 18026.6 18044.0 18151.4 18324.5 18474.2 43576.8 44286.3 44399.8 45274.5 45631.8 62830.4 63184.0 63875.4 64061.0 64471.0 8825.4 8926.6 8950.2 9073.1 9368.6 10154.7 12356.8 13332.4 13953.8 14052.6 12289.1 12312.9 12340.7 12368.4 12469.1 12454.4 12478.4 12583.2 13042.6 13114.3 12674.5 13017.9 13088.2 13252.7 13264.9 9135.1 9254.7 9303.3 9477.9 9661.9 9803.5 12533.3 12841.0 12846.8 13292.5 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | [#3] mootools.html Cycles:180 Average:6283.43 Median:2479.04 stddev:3687.42 (148.7%) stddev-sans-first:3678.35 14:49:13 INFO - PROCESS | 27570 | Values: 1249.0 1601.3 1629.4 1829.7 1899.4 9001.3 9424.9 9436.2 9716.5 9733.7 9224.6 9476.5 9525.0 9538.1 9574.2 2441.7 2453.6 2467.4 2472.3 2473.4 2354.3 2535.6 2535.9 2537.4 2537.7 2452.6 2467.0 2498.0 2501.0 2501.8 2449.4 2456.4 2459.4 2468.0 2478.3 2411.8 2416.5 2442.6 2443.2 2448.7 9222.7 9412.2 9463.6 9474.1 9589.0 5639.4 5765.0 5770.1 5775.3 5777.2 3430.5 3520.6 3544.5 3563.6 3574.5 1159.8 1172.0 1189.9 1192.5 1197.2 13423.6 13509.2 13525.3 13557.9 13576.9 4650.7 4721.2 4776.6 4792.5 4847.1 10456.0 10458.6 10483.6 10492.4 10505.3 5567.9 5583.8 5612.0 5613.7 5622.2 4516.5 4766.6 4824.1 5115.8 5228.7 4230.6 4257.7 4293.8 4330.6 4358.7 1258.9 1317.5 1317.6 1317.7 1333.6 1006.1 1009.7 1023.0 1042.7 1050.9 6087.8 6159.2 6321.5 6351.8 6363.3 441.6 456.5 480.5 485.9 490.9 4155.6 4262.6 4286.0 4308.9 4343.2 11963.6 12338.9 12345.4 12392.2 12400.4 11820.5 12154.8 12229.2 12321.4 12325.5 8621.1 8992.9 8993.1 9044.2 9129.8 6385.7 6594.9 6598.7 6637.8 6692.8 10958.8 11056.2 11403.5 11506.5 11507.3 11392.3 11558.1 11643.1 11705.5 11754.4 7862.6 8104.0 8282.2 8556.1 8564.9 9335.9 9405.3 9406.2 9468.3 9484.1 8528.7 8562.3 8660.4 8669.4 8712.3 8528.7 8543.4 8560.4 8564.2 8657.6 7574.4 7670.3 7710.9 7716.3 7724.1 5394.9 5411.6 5482.9 5582.4 5670.5 5300.2 5390.7 5607.1 5655.7 5829.4 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | [#4] prototype.html Cycles:180 Average:4844.93 Median:5480.73 stddev:1416.88 (25.9%) stddev-sans-first:1379.89 14:49:13 INFO - PROCESS | 27570 | Values: 338.5 346.7 351.9 367.2 370.4 5489.5 5532.2 5548.3 5553.6 5566.9 5445.8 5471.9 5490.2 5507.1 5511.5 3998.7 4148.2 4376.1 4381.7 4413.3 4332.1 4338.0 4340.1 4358.2 4369.7 4291.3 4320.8 4331.7 4334.9 4349.8 4148.8 4185.8 4291.4 4327.4 4339.2 3844.1 3845.7 3853.5 3856.0 3863.8 5259.8 5267.8 5313.5 5352.9 5357.7 4906.8 4947.9 4948.9 4959.9 4975.5 5798.8 5799.2 5807.2 5812.1 5815.4 4051.0 4093.0 4100.4 4229.2 4235.9 5959.2 5963.7 5972.4 5983.0 6012.4 4316.2 4316.5 4321.9 4341.6 4343.2 4068.5 4327.1 4327.9 4328.5 4343.6 4898.2 4906.6 4927.8 4944.2 4948.4 5682.4 5774.0 5776.2 5788.5 5802.9 1456.4 1488.8 1489.6 1490.3 1500.3 940.7 956.3 960.2 972.1 974.4 5040.8 5051.0 5061.5 5145.4 5146.3 5255.5 5275.9 5299.6 5333.4 5369.2 4998.0 5008.6 5011.9 5278.7 5733.9 3145.9 3157.9 3175.6 3188.4 3192.9 6043.9 6140.0 6186.6 6194.6 6202.9 5922.5 5985.5 6021.4 6048.7 6083.3 6122.1 6131.7 6148.0 6165.2 6225.4 5511.8 5787.5 5891.1 5913.0 6013.5 5806.3 5817.2 5818.4 5827.9 5859.0 5901.3 6077.1 6095.9 6117.5 6141.4 4225.9 4243.7 4243.9 4252.3 4264.9 5923.8 5928.7 5970.4 6005.2 6016.5 5579.3 5743.1 5838.8 5881.7 5900.8 5864.1 5874.2 5876.9 5903.4 5922.5 5730.9 5771.5 5773.2 5785.9 5789.5 5682.5 5829.8 5882.5 5894.9 5932.6 5658.4 5716.2 5761.6 5762.7 5814.5 14:49:13 INFO - PROCESS | 27570 | 14:49:13 INFO - PROCESS | 27570 | [#5] yui.html Cycles:180 Average:21986.12 Median:4016.55 stddev:32851.71 (817.9%) stddev-sans-first:32905.32 14:49:13 INFO - PROCESS | 27570 | Values: 790.2 805.5 811.5 890.8 937.5 14661.4 15636.6 15647.1 15665.2 15698.9 14275.7 14503.4 14539.5 14541.8 14550.7 871.5 888.4 891.6 897.0 898.0 888.1 889.8 891.6 891.7 892.2 939.7 943.9 945.2 945.3 945.5 932.7 932.9 936.3 940.0 942.5 691.7 692.1 692.9 693.4 694.3 14148.2 14267.9 14304.8 14307.8 14331.4 14167.1 14245.8 14342.8 14457.3 14663.2 11760.9 11953.2 12250.8 12344.4 12639.7 3994.6 4038.5 4053.5 4107.4 4115.4 50240.0 53201.0 70269.9 70528.3 78824.0 12944.3 13234.7 13292.5 13389.8 13694.1 24343.6 24348.3 24396.0 24410.5 24472.8 9436.3 9605.1 9685.5 9758.4 9853.0 4936.4 5143.6 5145.9 5201.8 5236.0 3858.3 3938.4 3940.1 3989.9 3990.0 683.3 689.7 690.8 693.2 693.7 724.6 799.5 806.8 832.8 833.4 10625.8 10838.9 10965.4 10986.2 11023.5 660.9 687.1 724.6 770.3 805.4 4389.6 4474.7 4563.7 5293.3 5415.1 127974.0 129700.0 131290.0 132044.0 132183.4 109794.0 110162.0 112104.0 112721.0 113990.0 69880.0 96450.0 96957.0 97743.5 97784.5 8935.4 9131.6 9206.7 9227.1 9273.8 55053.0 55166.4 55263.4 55875.0 56254.7 80333.0 84628.8 86507.6 86997.6 87457.6 12675.2 13164.6 13165.3 13238.7 13338.5 13536.7 13683.8 14493.6 14777.5 14869.4 12990.5 13664.7 13775.8 13776.1 14102.6 13449.9 13540.5 13555.3 13636.5 13824.4 12104.3 12198.3 12567.6 12571.5 13325.3 10268.7 10385.1 10547.8 10655.8 10928.7 10271.1 10741.4 10783.4 10787.9 10815.2 14:49:13 INFO - PROCESS | 27570 | -------- Summary: end -------- 14:49:13 INFO - PROCESS | 27570 | 14:49:14 INFO - TEST-INFO | 27570: exit 0 14:49:14 INFO - TEST-OK | dromaeo_css | took 1111947ms 14:49:14 INFO - TEST-START | kraken 14:49:14 INFO - operating with platform_type : linux_ 14:49:14 INFO - Initialising browser for kraken test... 14:49:14 INFO - TEST-INFO | started process 27761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQxuiSK/profile http://localhost:32940/getInfo.html) 14:49:17 INFO - PROCESS | 27761 | __metrics Screen width/height:1600/1200 14:49:17 INFO - PROCESS | 27761 | colorDepth:24 14:49:17 INFO - PROCESS | 27761 | Browser inner width/height: 1024/697 14:49:17 INFO - PROCESS | 27761 | __metrics 14:49:18 ERROR - PROCESS | 27761 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:49:18 INFO - PROCESS | 27761 | console.error: PushService: 14:49:18 INFO - PROCESS | 27761 | stateChangeProcessEnqueue: Error transitioning state 14:49:18 INFO - PROCESS | 27761 | UnknownError 14:49:19 INFO - TEST-INFO | 27761: exit 0 14:49:19 INFO - Browser initialized. 14:49:19 INFO - Running cycle 1/1 for kraken test... 14:49:19 INFO - TEST-INFO | started process 27856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQxuiSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:31 INFO - PROCESS | 27856 | RSS: Main: 145285120 14:49:31 INFO - PROCESS | 27856 | 14:49:33 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32940/tests/kraken/driver.html?testName=audio-beat-detection) 14:49:34 INFO - PROCESS | 27856 | RSS: Main: 194764800 14:49:34 INFO - PROCESS | 27856 | 14:49:37 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32940/tests/kraken/driver.html?testName=audio-dft) 14:49:37 INFO - PROCESS | 27856 | RSS: Main: 191881216 14:49:37 INFO - PROCESS | 27856 | 14:49:40 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32940/tests/kraken/driver.html?testName=audio-fft) 14:49:41 INFO - PROCESS | 27856 | RSS: Main: 277192704 14:49:41 INFO - PROCESS | 27856 | 14:49:43 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32940/tests/kraken/driver.html?testName=audio-oscillator) 14:49:44 INFO - PROCESS | 27856 | RSS: Main: 186212352 14:49:44 INFO - PROCESS | 27856 | 14:49:46 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32940/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:49:46 INFO - PROCESS | 27856 | RSS: Main: 185946112 14:49:46 INFO - PROCESS | 27856 | 14:49:49 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32940/tests/kraken/driver.html?testName=imaging-darkroom) 14:49:50 INFO - PROCESS | 27856 | RSS: Main: 220872704 14:49:50 INFO - PROCESS | 27856 | 14:49:53 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32940/tests/kraken/driver.html?testName=imaging-desaturate) 14:49:54 INFO - PROCESS | 27856 | RSS: Main: 220983296 14:49:54 INFO - PROCESS | 27856 | 14:49:57 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32940/tests/kraken/driver.html?testName=json-parse-financial) 14:49:57 INFO - PROCESS | 27856 | RSS: Main: 221204480 14:49:57 INFO - PROCESS | 27856 | 14:49:59 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32940/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:49:59 INFO - PROCESS | 27856 | RSS: Main: 200024064 14:49:59 INFO - PROCESS | 27856 | 14:50:01 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:50:02 INFO - PROCESS | 27856 | RSS: Main: 202477568 14:50:02 INFO - PROCESS | 27856 | 14:50:04 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:50:04 INFO - PROCESS | 27856 | RSS: Main: 208662528 14:50:04 INFO - PROCESS | 27856 | 14:50:07 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:50:07 INFO - PROCESS | 27856 | RSS: Main: 213147648 14:50:07 INFO - PROCESS | 27856 | 14:50:10 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:50:11 INFO - PROCESS | 27856 | RSS: Main: 213020672 14:50:11 INFO - PROCESS | 27856 | 14:50:12 INFO - PROCESS | 27856 | Cycle 1(1): loaded http://localhost:32940/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32940/tests/kraken/driver.html?testName=ai-astar) 14:50:13 INFO - PROCESS | 27856 | RSS: Main: 255418368 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | __start_tp_report 14:50:13 INFO - PROCESS | 27856 | _x_x_mozilla_page_load 14:50:13 INFO - PROCESS | 27856 | _x_x_mozilla_page_load_details 14:50:13 INFO - PROCESS | 27856 | |i|pagename|runs| 14:50:13 INFO - PROCESS | 27856 | |0;ai-astar;112;104;112;110;112;110;109;109;109;109 14:50:13 INFO - PROCESS | 27856 | |1;audio-beat-detection;151;130;132;171;130;124;152;124;162;128 14:50:13 INFO - PROCESS | 27856 | |2;audio-dft;161;161;161;160;160;161;160;161;160;161 14:50:13 INFO - PROCESS | 27856 | |3;audio-fft;79;74;76;125;75;75;74;75;98;75 14:50:13 INFO - PROCESS | 27856 | |4;audio-oscillator;83;83;109;83;83;84;119;83;83;131 14:50:13 INFO - PROCESS | 27856 | |5;imaging-gaussian-blur;117;118;116;116;117;117;118;117;117;117 14:50:13 INFO - PROCESS | 27856 | |6;imaging-darkroom;145;145;146;146;146;146;147;146;146;146 14:50:13 INFO - PROCESS | 27856 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;95;94 14:50:13 INFO - PROCESS | 27856 | |8;json-parse-financial;51;50;52;51;52;52;51;53;50;50 14:50:13 INFO - PROCESS | 27856 | |9;json-stringify-tinderbox;61;60;60;60;60;60;59;60;61;60 14:50:13 INFO - PROCESS | 27856 | |10;stanford-crypto-aes;77;76;77;75;76;80;84;80;125;77 14:50:13 INFO - PROCESS | 27856 | |11;stanford-crypto-ccm;122;131;120;116;118;114;124;118;126;133 14:50:13 INFO - PROCESS | 27856 | |12;stanford-crypto-pbkdf2;151;153;153;155;156;159;154;157;155;152 14:50:13 INFO - PROCESS | 27856 | |13;stanford-crypto-sha256-iterative;63;63;62;61;63;61;63;64;61;60 14:50:13 INFO - PROCESS | 27856 | __end_tp_report 14:50:13 INFO - PROCESS | 27856 | __start_cc_report 14:50:13 INFO - PROCESS | 27856 | _x_x_mozilla_cycle_collect,1576 14:50:13 INFO - PROCESS | 27856 | __end_cc_report 14:50:13 INFO - PROCESS | 27856 | __startTimestamp1466459413030__endTimestamp 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | ------- Summary: start ------- 14:50:13 INFO - PROCESS | 27856 | Number of tests: 14 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:2.37 (2.2%) stddev-sans-first:2.35 14:50:13 INFO - PROCESS | 27856 | Values: 112.0 104.0 112.0 110.0 112.0 110.0 109.0 109.0 109.0 109.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:141.50 stddev:17.09 (12.1%) stddev-sans-first:17.69 14:50:13 INFO - PROCESS | 27856 | Values: 151.0 130.0 132.0 171.0 130.0 124.0 152.0 124.0 162.0 128.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 14:50:13 INFO - PROCESS | 27856 | Values: 161.0 161.0 161.0 160.0 160.0 161.0 160.0 161.0 160.0 161.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.57 (22.1%) stddev-sans-first:17.52 14:50:13 INFO - PROCESS | 27856 | Values: 79.0 74.0 76.0 125.0 75.0 75.0 74.0 75.0 98.0 75.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.00 stddev:18.39 (22.2%) stddev-sans-first:19.07 14:50:13 INFO - PROCESS | 27856 | Values: 83.0 83.0 109.0 83.0 83.0 84.0 119.0 83.0 83.0 131.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 14:50:13 INFO - PROCESS | 27856 | Values: 117.0 118.0 116.0 116.0 117.0 117.0 118.0 117.0 117.0 117.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 14:50:13 INFO - PROCESS | 27856 | Values: 145.0 145.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:50:13 INFO - PROCESS | 27856 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:1.03 (2.0%) stddev-sans-first:1.09 14:50:13 INFO - PROCESS | 27856 | Values: 51.0 50.0 52.0 51.0 52.0 52.0 51.0 53.0 50.0 50.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 14:50:13 INFO - PROCESS | 27856 | Values: 61.0 60.0 60.0 60.0 60.0 60.0 59.0 60.0 61.0 60.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:77.00 stddev:15.10 (19.6%) stddev-sans-first:15.87 14:50:13 INFO - PROCESS | 27856 | Values: 77.0 76.0 77.0 75.0 76.0 80.0 84.0 80.0 125.0 77.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:123.00 stddev:6.30 (5.1%) stddev-sans-first:6.69 14:50:13 INFO - PROCESS | 27856 | Values: 122.0 131.0 120.0 116.0 118.0 114.0 124.0 118.0 126.0 133.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:155.00 stddev:2.42 (1.6%) stddev-sans-first:2.20 14:50:13 INFO - PROCESS | 27856 | Values: 151.0 153.0 153.0 155.0 156.0 159.0 154.0 157.0 155.0 152.0 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - PROCESS | 27856 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:63.00 stddev:1.29 (2.0%) stddev-sans-first:1.32 14:50:13 INFO - PROCESS | 27856 | Values: 63.0 63.0 62.0 61.0 63.0 61.0 63.0 64.0 61.0 60.0 14:50:13 INFO - PROCESS | 27856 | -------- Summary: end -------- 14:50:13 INFO - PROCESS | 27856 | 14:50:13 INFO - TEST-INFO | 27856: exit 0 14:50:13 INFO - TEST-OK | kraken | took 59471ms 14:50:13 INFO - SUITE-END | took 1171s 14:50:14 INFO - Completed test suite (00:19:31) 14:50:14 INFO - javascript benchmark 14:50:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.6776956189165, 3435.4159792835217, 3435.415979283523, 3458.56767904012, 3472.6180120186773, 5757.856053598657, 5795.362510123893, 5798.884022319551, 5810.767569297223, 5852.707364631773, 5794.130880367947, 5834.5040671542865, 5837.708114594275, 5844.269466316699, 5849.473547380735, 5046.788770695025, 5146.716930568818, 5227.764750586232, 5256.080185967459, 5337.359516857982, 5169.224616307546, 5227.163653815391, 5247.422783493813, 5297.920531192035, 5378.0857254266675, 5195.818146364878, 5200.635955483118, 5222.686638801665, 5287.101192797234, 5290.814821481249, 5224.216367544898, 5272.472752724716, 5278.736063196863, 5282.920756188661, 5316.601254905898, 4846.830360937351, 5029.949700503022, 5076.654686508078, 5117.872053198663, 5178.456262092502, 5697.4587414195585, 5714.7428365723645, 5716.828495145152, 5724.198612194296, 5747.511461525775, 6119.5176204203435, 6133.080037994292, 6169.7840575580085, 6185.134081228632, 6198.2871969723155, 5993.820185394445, 6240.219972503437, 6245.56281060326, 6257.499400047986, 6263.185785847856, 5694.544436445074, 5733.426657334275, 5757.453041961007, 5830.7959221427045, 5836.212111364954, 6328.493720502349, 6329.556931014877, 6335.904961425582, 6353.459955903708, 6373.0, 5814.186013958049, 6021.157038014681, 6095.0, 6098.481629061491, 6103.1455596216565, 6129.389354848411, 6223.813285601439, 6225.782097626561, 6228.688565571794, 6236.095766113888, 6147.66187859672, 6189.84525386869, 6217.347178546278, 6218.968905155445, 6226.750929962751, 5895.528357731457, 5952.851178720567, 6135.294441139326, 6166.044263139221, 6214.192155019909, 4706.364640773451, 4744.95255047452, 4751.4535828379485, 4758.738269395217, 4774.856754297377, 4218.514870789837, 4222.387753775747, 4222.9577704223175, 4231.640310573635, 4238.660907127422, 5649.745761440663, 5779.219805326311, 5874.50066744323, 5979.19280897082, 5987.9101813472835, 6219.440250377488, 6251.812445626639, 6262.6868656566985, 6264.718087686065, 6273.12176295319, 5904.675242861684, 5921.289445266596, 5945.583809133319, 5947.226860508193, 5962.37395073511, 4849.175640141185, 5019.648624596243, 5075.847724568269, 5078.771455284521, 5138.229265610188, 6348.079528468346, 6365.586236894587, 6385.169927909435, 6386.010168423902, 6389.424951754364, 6139.0, 6205.0, 6205.131281620667, 6213.440790328892, 6214.378562143749, 6182.412670796267, 6196.225471816023, 6230.6884655767935, 6259.280182779039, 6380.298167201697, 6027.638341699512, 6061.454469097803, 6074.544409169242, 6079.331273559993, 6107.69461526913, 6048.183495228088, 6137.60105593135, 6144.354842741626, 6145.0, 6160.16837726919, 5968.582199246011, 6017.819465416044, 6032.215811944419, 6077.908831367621, 6105.267367915879, 6032.75868965254, 6034.064719968412, 6039.547033972557, 6049.243844519435, 6061.393860613904, 5779.450952159538, 5843.152742852348, 5952.613080149777, 5954.791582294599, 5964.522838173022, 5903.26209223847, 5975.671338076448, 5985.820425387245, 6095.152603231562, 6102.084687296941, 5973.104034394875, 6016.0, 6016.2479690038745, 6026.397360263939, 6031.728572214173], "value": 5680.85290385663, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.8325643704419, 636.7866764633848, 644.6969924135656, 653.7679123911012, 660.1681880830158, 47631.0, 49446.75276623612, 49637.0, 52597.73701131499, 52648.21027684588, 50580.74709626456, 51251.23123153156, 51271.0, 51370.22944655824, 51449.22826157611, 8623.094575069621, 8848.690295839648, 9001.23489503393, 10801.40592267425, 11084.722881927935, 10907.672769816918, 11047.281926674741, 11053.778924421547, 11060.944695276552, 11061.834072488919, 11110.833337499944, 11180.329180249171, 11184.552617895275, 11187.776244475106, 11198.608048718297, 8523.360747943898, 10877.782444351147, 11171.720706982309, 11174.888251117465, 11534.711632209179, 10947.0, 10980.450977451095, 11036.392998385167, 11046.281991670516, 11056.502457389437, 46138.30792538081, 46403.767981160214, 46439.30341044887, 46457.30314045292, 46584.0, 3618.203995121069, 3653.06846754076, 3691.9815400923094, 3812.90467738306, 4343.305071188626, 34067.14832129217, 34618.48072278918, 34906.47640285398, 34946.47580286298, 35135.824320878484, 4420.0, 4636.9536304637195, 4649.674522783406, 4739.336492890998, 4764.166270902613, 403220.0, 405627.9718601418, 405805.0, 406101.0, 407063.0, 38659.0, 39773.0, 66368.33631663719, 66469.0, 66702.0, 41216.0, 41400.585994140274, 41579.792101039304, 41607.1678566427, 41618.58381416208, 15807.762883556756, 17753.0, 18071.277148913898, 18074.63850722978, 18078.90960545189, 15117.54647360581, 15399.07605543671, 15401.691966160613, 15779.526614201592, 15785.289661965238, 9282.071792820663, 9297.395669281475, 9334.673286435078, 9367.344285899922, 9383.436993780395, 3552.8223588820456, 3562.358775420449, 3593.975716920665, 3606.880048757858, 3634.454831775202, 1116.609186784619, 1121.5121422181378, 1132.8923752243525, 1214.0, 1228.4717571444187, 34061.659383406346, 34734.47898281528, 34786.0, 34816.129596759776, 35017.474737878954, 1194.6834088966432, 1201.0691713921606, 1225.8774122587847, 1248.375812093953, 1281.39774306076, 17852.642947141245, 18090.728639070425, 18129.36547220841, 18181.45455636333, 18225.08874556267, 163645.0, 165377.0, 165572.0, 165605.0, 165870.170649146, 64633.0, 66103.0, 66161.6691916547, 66596.6670166646, 66606.33393666099, 1369.9520516781865, 1419.9006069575237, 1425.2446203512154, 1478.9260536973109, 1537.8231503377256, 30393.0, 35707.95887697898, 36007.63992360148, 36889.26221475502, 37574.624253757116, 63611.36388636239, 64540.67729661322, 64629.0, 64640.0, 64690.35309646843, 528742.0, 542895.0, 545255.0, 545365.0, 546147.0, 27757.30606734767, 28596.571051433828, 28649.28376790597, 31092.68907310898, 31618.0, 45736.31395529136, 52767.472325276256, 58499.70750146222, 58684.706576466844, 59123.40876591179, 45387.0, 46208.76895615501, 46384.0, 46455.30317045179, 47797.522024779304, 44732.77633611811, 45234.32148517709, 45439.77280113578, 45553.54446455493, 46871.76564117294, 45430.31854522251, 46301.53698463107, 47126.0, 47869.76065119652, 48447.78880528015, 33944.32111357809, 35985.28029439449, 36136.0, 36518.26963460663, 37112.25775484529, 28995.56506652444, 29167.7083229165, 29197.708022919498, 29364.412711746074, 29585.408291833613], "value": 20506.894592107867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.1314158541331, 434.04379278535714, 435.82566973210714, 437.9408779814728, 441.7614488176385, 19286.51783705408, 19697.21211151552, 20472.590548189026, 20492.69260961083, 20508.077136528856, 19296.324628638, 19653.803461965377, 20244.39266821994, 20758.896205518988, 20801.375958721226, 5919.23050003499, 6018.969905150469, 6095.939040609582, 6110.694465276741, 6133.1106989486725, 6014.278286605613, 6052.394760523956, 6059.394060593927, 6073.696315184223, 6107.267127944653, 5982.192404025466, 6007.7596896124105, 6023.789167379143, 6024.096385542181, 6035.577509574332, 5766.769329226826, 5985.371535988724, 6066.696665166724, 6072.696365181745, 6174.765046990575, 5711.485966263056, 5964.463198312129, 5971.761129554856, 5978.073398623221, 5993.220881285405, 14164.858351416457, 14643.780343294859, 14805.111693298437, 14805.259737013106, 14857.554273371816, 2323.848949818256, 2326.953460930789, 2368.206650771976, 2386.296042667417, 2411.3368823573655, 10746.301490403099, 11185.384803835786, 11272.887271127265, 11285.717857053558, 14389.64025899361, 1984.811442912921, 2086.54096098858, 2116.777738337468, 2203.933881983543, 2226.25420484138, 89460.5526972361, 90193.54903225442, 90353.54823225907, 90549.54725226332, 92081.0, 11449.141314401453, 11738.767429419959, 11943.76112477763, 12112.09159313055, 12241.510339586495, 17016.659666806594, 17302.22140003703, 17317.91341043312, 17325.306987720604, 17329.56676083083, 8865.911340886509, 8877.822443551093, 9077.591508382138, 9094.181523662903, 9144.817103658022, 8818.77953051166, 8942.910570894337, 9088.772780680536, 9168.404053736485, 9225.769355766159, 5009.423916249605, 5106.259592359087, 5234.476552344735, 5249.763760630781, 5302.337207849019, 2091.926782562603, 2103.8533998970597, 2136.102836808544, 2148.2481131604127, 2158.8057074863336, 989.5299732627088, 1029.1046789293214, 1085.8262677971486, 1088.3089238333714, 1155.5089087138101, 19068.61862762737, 20081.59836803256, 20419.0, 20461.283855065, 20812.27157049496, 1006.6527048168371, 1022.3712416863724, 1049.6273822792798, 1098.2751384086625, 1098.609993452313, 10837.620683276047, 11183.888161118448, 11345.773084538263, 11450.885491145149, 11487.0810335173, 504484.0, 516730.0, 519719.0, 520860.0, 522760.0, 66873.66563167152, 83073.75389369164, 102829.48585257026, 107874.46062769636, 108447.0, 1070.8982646648556, 1090.487470888681, 1114.9108071354271, 1117.1063149480383, 1117.1621284036971, 18026.639467210844, 18044.0, 18151.364702235358, 18324.54188645241, 18474.168662409957, 43576.782116090486, 44286.33570496381, 44399.778001109786, 45274.54725452703, 45631.77184114058, 62830.37169628428, 63184.0, 63875.36124638694, 64061.0, 64471.0, 8825.426347287406, 8926.598303076247, 8950.23922966555, 9073.092690730875, 9368.625254989996, 10154.695359139238, 12356.752864942471, 13332.40004199794, 13953.790693139408, 14052.648683782987, 12289.139760217056, 12312.876871231172, 12340.691482713004, 12368.381580921097, 12469.064820138345, 12454.43955022008, 12478.438470268678, 12583.182093163916, 13042.608721738363, 13114.278714670785, 12674.493020279086, 13017.934910325388, 13088.214707117604, 13252.668683282995, 13264.933675331562, 9135.132162444557, 9254.722358329262, 9303.348765586345, 9477.905220947978, 9661.9033809661, 9803.509824508603, 12533.310667913353, 12841.0, 12846.807297890726, 13292.468301267842], "value": 9741.802159579827, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1248.9562865299715, 1601.3034330066419, 1629.3808352825924, 1829.734688470172, 1899.3637131560927, 9001.324900632455, 9424.905750942497, 9436.245100391969, 9716.465594392306, 9733.707988760349, 9224.584893679783, 9476.526173691322, 9525.047495250488, 9538.093881081286, 9574.186194173486, 2441.7192022917325, 2453.595156799135, 2467.4201562632857, 2472.320111969214, 2473.4311108444977, 2354.2937118864356, 2535.5943049112147, 2535.936601584966, 2537.4417628121782, 2537.6827896512937, 2452.619843924195, 2466.950660986779, 2497.950040999179, 2500.987495062522, 2501.799856011524, 2449.35092200567, 2456.4104614892476, 2459.4220358215807, 2468.0374653885, 2478.31846242283, 2411.770881766229, 2416.5046165536023, 2442.6336049592524, 2443.230382429529, 2448.669429626995, 9222.677206297747, 9412.247020238365, 9463.621455141785, 9474.099960503741, 9589.0, 5639.379668236491, 5765.048766953448, 5770.105633626795, 5775.335836378787, 5777.220075289824, 3430.5368775215525, 3520.612732599412, 3544.5392099027217, 3563.6436356364416, 3574.4638304254568, 1159.7564511452606, 1172.0, 1189.9345535995517, 1192.4931903940876, 1197.239752756997, 13423.597292081253, 13509.189448633015, 13525.256110913895, 13557.864421355758, 13576.93211533946, 4650.697704649255, 4721.1501929652995, 4776.59398951093, 4792.520747925249, 4847.054824309296, 10456.0, 10458.581656733797, 10483.580656773644, 10492.4229167395, 10505.264631475724, 5567.860803479945, 5583.804566840141, 5612.017896868032, 5613.7193140342815, 5622.156676498558, 4516.458025036951, 4766.61867050642, 4824.059308434892, 5115.7953681852305, 5228.712420816815, 4230.640395566411, 4257.723247988933, 4293.828246870153, 4330.631896288788, 4358.65130789536, 1258.8678188790045, 1317.5059352742721, 1317.5783749200164, 1317.736452709462, 1333.6265845563257, 1006.094514936563, 1009.7021378693303, 1023.0281232828843, 1042.7393151712072, 1050.8633877595864, 6087.817365479043, 6159.19930409044, 6321.54978644086, 6351.841203969938, 6363.331850155667, 441.6180004296261, 456.53661533542817, 480.5434836904954, 485.90039041996306, 490.91654418748897, 4155.625993660585, 4262.552431994658, 4285.957140428556, 4308.89227769302, 4343.153085148366, 11963.64109076729, 12338.938305308417, 12345.444454999546, 12392.194507356993, 12400.441980110916, 11820.527178912918, 12154.817677734842, 12229.205101668364, 12321.383930803604, 12325.50697972089, 8621.09478504748, 8992.910070899206, 8993.055729148346, 9044.231240344512, 9129.77175570616, 6385.718992422289, 6594.934050659432, 6598.67006649656, 6637.834054148491, 6692.76575319881, 10958.780824383308, 11056.226064175431, 11403.486843091914, 11506.539738410369, 11507.252028618113, 11392.316461012366, 11558.075353971493, 11643.12676549279, 11705.531778729097, 11754.412279386168, 7862.646180921986, 8103.959480202561, 8282.213189746964, 8556.144385561394, 8564.871526927225, 9335.906640933776, 9405.294602904673, 9406.153446189875, 9468.337216394786, 9484.146426821619, 8528.74413767588, 8562.272206862486, 8660.35047371437, 8669.393142479967, 8712.303015758851, 8528.701495447629, 8543.359248056297, 8560.357973151915, 8564.229219370038, 8657.567121644019, 7574.356179724565, 7670.348020418104, 7710.884336734841, 7716.266954639525, 7724.1117271514495, 5394.946050539601, 5411.594130440154, 5482.890342193214, 5582.3859375467855, 5670.5180059695385, 5300.1519756838725, 5390.676559406448, 5607.102863541977, 5655.688937108334, 5829.358770535153], "value": 4887.407924380095, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.5024014698393, 346.7243541384599, 351.91554027033504, 367.1610370303856, 370.4313878196968, 5489.517830185848, 5532.170174473831, 5548.250986116873, 5553.639013464122, 5566.888662226758, 5445.754941027653, 5471.945280547194, 5490.16079613329, 5507.146392309189, 5511.5039646431815, 3998.740081894682, 4148.191102734968, 4376.1247750449875, 4381.671374646899, 4413.3159360299205, 4332.111917057012, 4337.956620433787, 4340.110277393127, 4358.171947330013, 4369.737815731066, 4291.334843099325, 4320.82716691332, 4331.740095594275, 4334.916270932267, 4349.847755328564, 4148.846876373947, 4185.769782661952, 4291.356296555542, 4327.3725309830215, 4339.175556644243, 3844.0774214188395, 3845.6731177849915, 3853.477876238898, 3856.014152711356, 3863.8454461821493, 5259.815906443256, 5267.815626453094, 5313.548348390392, 5352.892942141174, 5357.687366595209, 4906.803727850882, 4947.876303092451, 4948.906933166771, 4959.851204463872, 4975.527324904128, 5798.768049278024, 5799.21710569072, 5807.2160258365, 5812.128180772876, 5815.447532484407, 4050.9594904050878, 4092.9590704092875, 4100.446439730628, 4229.175310814392, 4235.851745188935, 5959.225300710923, 5963.731632076524, 5972.372900845392, 5983.0, 6012.428819262163, 4316.158349121891, 4316.460442444694, 4321.876312158798, 4341.587549182823, 4343.2399330117105, 4068.450759147477, 4327.052826228147, 4327.913441731148, 4328.502222244482, 4343.630791382705, 4898.191798353303, 4906.55840974314, 4927.753612319441, 4944.170656856986, 4948.4309304429735, 5682.448469706434, 5773.979107312407, 5776.249087618583, 5788.453080644503, 5802.941970580325, 1456.3592019511382, 1488.776683497484, 1489.5903626502582, 1490.344248530643, 1500.2873635023277, 940.6660635474445, 956.3257903178243, 960.1550635440767, 972.0668158567854, 974.4348277998744, 5040.765012571944, 5050.974745126251, 5061.493850614983, 5145.3568303962, 5146.305248791366, 5255.503635773053, 5275.868103297448, 5299.6025298102795, 5333.359996800409, 5369.19462080691, 4997.9504304096545, 5008.574271186917, 5011.949880501148, 5278.683279003272, 5733.942660573424, 3145.8741650334186, 3157.936841263162, 3175.555422240888, 3188.4101441233447, 3192.8824911280867, 6043.912255185238, 6139.969300153471, 6186.608013196995, 6194.5973511721595, 6202.937970620236, 5922.496587790086, 5985.491233245136, 6021.367756385516, 6048.667323297261, 6083.300420451616, 6122.112293717475, 6131.662758548234, 6147.969260153671, 6165.167702360213, 6225.439710426083, 5511.779528818803, 5787.450192231731, 5891.1457838614015, 5912.970435147797, 6013.488853447506, 5806.303243610793, 5817.2146760188525, 5818.418158184148, 5827.912581311199, 5859.00396932514, 5901.3213480448585, 6077.149199112217, 6095.939040609537, 6117.480014198843, 6141.447269745744, 4225.915481690287, 4243.724157929724, 4243.872683819491, 4252.277112890878, 4264.893377665583, 5923.763049477972, 5928.703564821828, 5970.4178392726835, 6005.1892994446935, 6016.548758843017, 5579.302587176603, 5743.1385292206505, 5838.795642152504, 5881.73532191061, 5900.822975310747, 5864.149698293638, 5874.236349274567, 5876.911846322223, 5903.350631430453, 5922.496587789914, 5730.914036289542, 5771.538276937751, 5773.162891380643, 5785.884282314246, 5789.478946894799, 5682.494139053071, 5829.766809327749, 5882.470577648032, 5894.882102358015, 5932.597298094279, 5658.405867383865, 5716.199732037435, 5761.617211869205, 5762.6830524319885, 5814.476697097281], "value": 4395.812573339303, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.1584812175033, 805.476440313796, 811.4765975945519, 890.7817584691752, 937.4562753602911, 14661.41354345825, 15636.609084772857, 15647.139407332592, 15665.21673916304, 15698.921505392456, 14275.714485710305, 14503.419863205485, 14539.49111781088, 14541.78187327191, 14550.708985820278, 871.4858233642166, 888.4314039015036, 891.6210610490548, 896.9910300897005, 897.9910200898005, 888.1251966812684, 889.8353804546148, 891.6344298837478, 891.7235656946367, 892.2282225874604, 939.6945992552413, 943.9339246252731, 945.1918609588806, 945.3382632157483, 945.4894357047186, 932.6782260120248, 932.9393589416666, 936.2977766674994, 940.041158018817, 942.4910548303908, 691.7094820175538, 692.0968136581758, 692.8648913461877, 693.4071368979551, 694.3056943056943, 14148.151110933377, 14267.928660356736, 14304.785428218585, 14307.78538321926, 14331.355089021019, 14167.14997100167, 14245.78631320531, 14342.78485822703, 14457.349419276157, 14663.193524356157, 11760.941195293968, 11953.22304050234, 12250.75498490034, 12344.444499997522, 12639.747205055939, 3994.6205110514456, 4038.474998250238, 4053.4730485037053, 4107.424960505532, 4115.36211887158, 50240.0, 53201.0, 70269.94595081078, 70528.29471705269, 78824.0, 12944.288064156563, 13234.73530529384, 13292.468301268034, 13389.799153012713, 13694.109882857583, 24343.634845477332, 24348.269551913472, 24396.0, 24410.51178976446, 24472.755272447048, 9436.339456237998, 9605.087516685904, 9685.515724213761, 9758.365706229073, 9853.0, 4936.432310284292, 5143.614228932845, 5145.948540514547, 5201.7919283229, 5235.973820130951, 3858.3440815060944, 3938.4289278055094, 3940.133170702441, 3989.900252493711, 3989.9601003989587, 683.2859661653584, 689.7206631314326, 690.8203866994517, 693.2409012131708, 693.6774399904069, 724.6376811594203, 799.5322736199272, 806.8103995561156, 832.7959649885817, 833.3832963606887, 10625.787484250272, 10838.891611083945, 10965.396903170242, 10986.176036797273, 11023.503942322615, 660.8942569189003, 687.051868421575, 724.6014691919529, 770.2874840772375, 805.399977017126, 4389.626881715026, 4474.664400170001, 4563.748993805306, 5293.311869456946, 5415.1335786274, 127974.0, 129700.0, 131290.0, 132044.0, 132183.3822286307, 109794.0, 110162.0, 112104.0, 112721.0, 113990.0, 69880.0, 96450.0, 96957.0, 97743.51128244455, 97784.51107744558, 8935.374523783274, 9131.634734610807, 9206.723798286062, 9227.123423274777, 9273.814523709623, 55052.97208653402, 55166.448335517736, 55263.44736552583, 55875.0, 56254.71872640611, 80333.0, 84628.79609138174, 86507.5674621648, 86997.56501217453, 87457.56271218603, 12675.17611355259, 13164.60506184816, 13165.341732913508, 13238.669033274246, 13338.466461341826, 13536.729265414833, 13683.794743079061, 14493.565193044225, 14777.482788102365, 14869.405223790927, 12990.480380784662, 13664.726705466033, 13775.793363099761, 13776.10455320401, 14102.576922692335, 13449.932750336186, 13540.458381664623, 13555.322233888463, 13636.454541818217, 13824.37790299459, 12104.273743575413, 12198.32909190003, 12567.622971310875, 12571.497140114294, 13325.267110308638, 10268.691939241835, 10385.117265032557, 10547.841782373118, 10655.786884262427, 10928.672139835817, 10271.126954208677, 10741.355518668905, 10783.352998820095, 10787.892121078688, 10815.188860835626], "value": 7927.726298864804, "unit": "score"}], "name": "dromaeo_css", "value": 7604.024675386513, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 112.0, 110.0, 112.0, 110.0, 109.0, 109.0, 109.0, 109.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 130.0, 132.0, 171.0, 130.0, 124.0, 152.0, 124.0, 162.0, 128.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 160.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 76.0, 125.0, 75.0, 75.0, 74.0, 75.0, 98.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 109.0, 83.0, 83.0, 84.0, 119.0, 83.0, 83.0, 131.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 52.0, 51.0, 52.0, 52.0, 51.0, 53.0, 50.0, 50.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0, 61.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 75.0, 76.0, 80.0, 84.0, 80.0, 125.0, 77.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 131.0, 120.0, 116.0, 118.0, 114.0, 124.0, 118.0, 126.0, 133.0], "value": 122.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 153.0, 155.0, 156.0, 159.0, 154.0, 157.0, 155.0, 152.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 61.0, 63.0, 61.0, 63.0, 64.0, 61.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1477.7, "alertThreshold": 2.0}]} 14:50:14 INFO - Return code: 0 14:50:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:50:14 ERROR - # TBPL SUCCESS # 14:50:14 INFO - Running post-action listener: _resource_record_post_action 14:50:14 INFO - ##### 14:50:14 INFO - ##### Finished run-tests step (success) 14:50:14 INFO - ##### 14:50:14 INFO - Running post-run listener: _resource_record_post_run 14:50:14 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 507543552; Read time: 4; Write time: 389644 14:50:14 INFO - TinderboxPrint: CPU usage
13.1% 14:50:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 14:50:14 INFO - TinderboxPrint: I/O write bytes / time
507,543,552 / 389,644 14:50:14 INFO - TinderboxPrint: CPU idle
8,248.8 (86.9%) 14:50:14 INFO - TinderboxPrint: CPU user
1,199.8 (12.6%) 14:50:14 INFO - TinderboxPrint: Swap in / out
0 / 0 14:50:14 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252256256; Read time: 0; Write time: 261776 14:50:14 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254701568; Read time: 4; Write time: 127852 14:50:14 INFO - Running post-run listener: _upload_blobber_files 14:50:14 INFO - Blob upload gear active. 14:50:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:50:14 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 14:50:14 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'] 14:50:14 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 14:50:15 INFO - (blobuploader) - INFO - Open directory for files ... 14:50:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:50:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:50:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:16 INFO - (blobuploader) - INFO - Done attempting. 14:50:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:50:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:50:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:21 INFO - (blobuploader) - INFO - Done attempting. 14:50:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:50:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:50:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:21 INFO - (blobuploader) - INFO - Done attempting. 14:50:21 INFO - (blobuploader) - INFO - Iteration through files over. 14:50:21 INFO - Return code: 0 14:50:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:50:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:50:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/345f864f122970912a66f45e80cf72a912c8a8cd2035c203ac4ac98c9b79b71c014b282d8826e849451e09f0976804f8a18c15acf95a81ea585cbcea2157746b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2eb252c7b7339159a1bef6fb688c2f7e714579b1831ef8691187c929fb156ec4ea525dd23e060c9cfdfe31064828c7f586cf376cfbfc11a302ea37f5a5488bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639447bdb7297aaa830a6db341238c97089682bf7da272ae5efb028f212b42c71642d816a992f2bcc464d548ba1e81950943ec66ef508489ba8c4901fb2824b8"} 14:50:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:50:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:50:21 INFO - Contents: 14:50:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/345f864f122970912a66f45e80cf72a912c8a8cd2035c203ac4ac98c9b79b71c014b282d8826e849451e09f0976804f8a18c15acf95a81ea585cbcea2157746b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2eb252c7b7339159a1bef6fb688c2f7e714579b1831ef8691187c929fb156ec4ea525dd23e060c9cfdfe31064828c7f586cf376cfbfc11a302ea37f5a5488bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639447bdb7297aaa830a6db341238c97089682bf7da272ae5efb028f212b42c71642d816a992f2bcc464d548ba1e81950943ec66ef508489ba8c4901fb2824b8"} 14:50:21 INFO - Running post-run listener: copy_logs_to_upload_dir 14:50:21 INFO - Copying logs to upload dir... 14:50:21 INFO - mkdir: /builds/slave/test/build/upload/logs 14:50:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.936417 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-20 14:50:21.089816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 14:50:21.096941) ========= 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=923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305 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/345f864f122970912a66f45e80cf72a912c8a8cd2035c203ac4ac98c9b79b71c014b282d8826e849451e09f0976804f8a18c15acf95a81ea585cbcea2157746b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2eb252c7b7339159a1bef6fb688c2f7e714579b1831ef8691187c929fb156ec4ea525dd23e060c9cfdfe31064828c7f586cf376cfbfc11a302ea37f5a5488bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639447bdb7297aaa830a6db341238c97089682bf7da272ae5efb028f212b42c71642d816a992f2bcc464d548ba1e81950943ec66ef508489ba8c4901fb2824b8"} build_url:https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011429 build_url: 'https://queue.taskcluster.net/v1/task/N2zcwoffSDWCaC34sUQSZw/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/345f864f122970912a66f45e80cf72a912c8a8cd2035c203ac4ac98c9b79b71c014b282d8826e849451e09f0976804f8a18c15acf95a81ea585cbcea2157746b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2eb252c7b7339159a1bef6fb688c2f7e714579b1831ef8691187c929fb156ec4ea525dd23e060c9cfdfe31064828c7f586cf376cfbfc11a302ea37f5a5488bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639447bdb7297aaa830a6db341238c97089682bf7da272ae5efb028f212b42c71642d816a992f2bcc464d548ba1e81950943ec66ef508489ba8c4901fb2824b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 14:50:21.125259) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:50:21.125612) ========= 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=923d835db2ba3686ebe126b700000192-1466457714.65621-1152603305 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 14:50:21.206382) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 14:50:21.209773) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 14:50:21.210094) ========= ========= Total master_lag: 0.19 =========