builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1467032260.04 results: success (0) buildid: 20160627025709 builduid: 149c76c2819e40dabdae4b1b3c7151ea revision: 9f311d92805addcd093ba46dabfcf385a0f7fed3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.040458) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.040929) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.041205) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.055730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.056040) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 05:57: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% 226M=0s 2016-06-27 05:57:40 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097402 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.167121) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.167451) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018370 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.217737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:40.218139) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9f311d92805addcd093ba46dabfcf385a0f7fed3 --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 9f311d92805addcd093ba46dabfcf385a0f7fed3 --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=516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 05:57:40,607 truncating revision to first 12 chars 2016-06-27 05:57:40,607 Setting DEBUG logging. 2016-06-27 05:57:40,607 attempt 1/10 2016-06-27 05:57:40,607 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9f311d92805a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 05:57:41,199 unpacking tar archive at: mozilla-aurora-9f311d92805a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.935042 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:41.166768) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:41.167090) ========= script_repo_revision: 9f311d92805addcd093ba46dabfcf385a0f7fed3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:41.167499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:41.167789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:41.178536) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-06-27 05:57:41.178927) ========= /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=516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:41 INFO - MultiFileLogger online at 20160627 05:57:41 in /builds/slave/test 05:57: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 05:57:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:41 INFO - {'append_to_log': False, 05:57:41 INFO - 'base_work_dir': '/builds/slave/test', 05:57:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:57:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:41 INFO - 'branch': 'Mozilla-Aurora', 05:57:41 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:41 INFO - 'config_files': ('talos/linux_config.py',), 05:57:41 INFO - 'default_actions': ('clobber', 05:57:41 INFO - 'read-buildbot-config', 05:57:41 INFO - 'download-and-extract', 05:57:41 INFO - 'populate-webroot', 05:57:41 INFO - 'create-virtualenv', 05:57:41 INFO - 'install', 05:57:41 INFO - 'run-tests'), 05:57:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:41 INFO - 'download_minidump_stackwalk': True, 05:57:41 INFO - 'download_symbols': 'ondemand', 05:57:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:41 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:41 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:41 INFO - 'installer_path': 'installer.exe', 05:57:41 INFO - 'log_level': 'info', 05:57:41 INFO - 'log_name': 'talos', 05:57:41 INFO - 'log_to_console': True, 05:57:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:41 INFO - 'opt_config_files': (), 05:57:41 INFO - 'pip_index': False, 05:57:41 INFO - 'sps_profile': False, 05:57:41 INFO - 'sps_profile_interval': 0, 05:57:41 INFO - 'suite': 'dromaeojs', 05:57:41 INFO - 'system_bits': '32', 05:57:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:41 INFO - 'title': 'talos-linux64-ix-061', 05:57:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:41 INFO - 'use_talos_json': True, 05:57:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:41 INFO - 'work_dir': 'build'} 05:57:41 INFO - ##### 05:57:41 INFO - ##### Running clobber step. 05:57:41 INFO - ##### 05:57:41 INFO - Running pre-action listener: _resource_record_pre_action 05:57:41 INFO - Running main action method: clobber 05:57:41 INFO - rmtree: /builds/slave/test/build 05:57:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:42 INFO - Running post-action listener: _resource_record_post_action 05:57:42 INFO - ##### 05:57:42 INFO - ##### Finished clobber step (success) 05:57:42 INFO - ##### 05:57:42 INFO - ##### 05:57:42 INFO - ##### Running read-buildbot-config step. 05:57:42 INFO - ##### 05:57:42 INFO - Running pre-action listener: _resource_record_pre_action 05:57:42 INFO - Running main action method: read_buildbot_config 05:57:42 INFO - Using buildbot properties: 05:57:42 INFO - { 05:57:42 INFO - "project": "", 05:57:42 INFO - "product": "firefox", 05:57:42 INFO - "script_repo_revision": "production", 05:57:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:57:42 INFO - "repository": "", 05:57:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:57:42 INFO - "buildid": "20160627025709", 05:57:42 INFO - "pgo_build": "True", 05:57:42 INFO - "basedir": "/builds/slave/test", 05:57:42 INFO - "buildnumber": 316, 05:57:42 INFO - "slavename": "talos-linux64-ix-061", 05:57:42 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:57:42 INFO - "platform": "ubuntu64_hw", 05:57:42 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:57:42 INFO - "branch": "mozilla-aurora", 05:57:42 INFO - "repo_path": "releases/mozilla-aurora", 05:57:42 INFO - "revision": "9f311d92805addcd093ba46dabfcf385a0f7fed3", 05:57:42 INFO - "stage_platform": "linux64-pgo", 05:57:42 INFO - "builduid": "149c76c2819e40dabdae4b1b3c7151ea", 05:57:42 INFO - "slavebuilddir": "test" 05:57:42 INFO - } 05:57:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 05:57:42 INFO - Running post-action listener: _resource_record_post_action 05:57:42 INFO - ##### 05:57:42 INFO - ##### Finished read-buildbot-config step (success) 05:57:42 INFO - ##### 05:57:42 INFO - ##### 05:57:42 INFO - ##### Running download-and-extract step. 05:57:42 INFO - ##### 05:57:42 INFO - Running pre-action listener: _resource_record_pre_action 05:57:42 INFO - Running main action method: download_and_extract 05:57:42 INFO - mkdir: /builds/slave/test/build/tests 05:57: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')]} 05:57:42 INFO - https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:57:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:57:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:57:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:43 INFO - Downloaded 1361 bytes. 05:57:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 05:57:43 INFO - Using the following test package requirements: 05:57:43 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 05:57:43 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:57:43 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:57:43 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'jsshell-linux-x86_64.zip'], 05:57:43 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:57:43 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 05:57:43 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:57:43 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:57:43 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:57:43 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 05:57:43 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:57:43 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:57:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:43 INFO - https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:57:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:57:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:57:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:44 INFO - Downloaded 24517304 bytes. 05:57:44 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'] 05:57:44 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 05:57:45 INFO - Return code: 0 05:57:45 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 05:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:45 INFO - https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:57:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:57:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 05:57:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:47 INFO - Downloaded 24517304 bytes. 05:57:47 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'] 05:57:47 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 05:57:49 INFO - Return code: 0 05:57:49 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')]} 05:57:49 INFO - https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:57:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 05:57:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 05:57:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:50 INFO - Downloaded 10833369 bytes. 05:57:50 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'] 05:57:50 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 05:57:50 INFO - Return code: 0 05:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:50 INFO - https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:57:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:57:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:57:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DBiGjFYkRpWvoIGPxASbvA/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 05:57:51 INFO - Downloaded 63617093 bytes. 05:57:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:57:51 INFO - mkdir: /builds/slave/test/properties 05:57:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:51 INFO - Contents: 05:57:51 INFO - build_url:https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 05:57:52 INFO - Running post-action listener: _resource_record_post_action 05:57:52 INFO - Running post-action listener: set_extra_try_arguments 05:57:52 INFO - ##### 05:57:52 INFO - ##### Finished download-and-extract step (success) 05:57:52 INFO - ##### 05:57:52 INFO - ##### 05:57:52 INFO - ##### Running populate-webroot step. 05:57:52 INFO - ##### 05:57:52 INFO - Running pre-action listener: _resource_record_pre_action 05:57:52 INFO - Running main action method: populate_webroot 05:57:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:52 INFO - '--tppagecycles', 05:57:52 INFO - '7'], 05:57:52 INFO - 'tests': ['tsvgm']}}, 05:57:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['damp', 'tps']}, 05:57:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'tests': ['damp', 'tps']}, 05:57:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['dromaeo_dom']}, 05:57:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['a11yr', 05:57:52 INFO - 'ts_paint', 05:57:52 INFO - 'tpaint', 05:57:52 INFO - 'sessionrestore', 05:57:52 INFO - 'sessionrestore_no_auto_restore', 05:57:52 INFO - 'tabpaint']}, 05:57:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:52 INFO - 'ts_paint', 05:57:52 INFO - 'tpaint', 05:57:52 INFO - 'sessionrestore', 05:57:52 INFO - 'sessionrestore_no_auto_restore', 05:57:52 INFO - 'tabpaint']}, 05:57:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:52 INFO - 'ts_paint', 05:57:52 INFO - 'tpaint', 05:57:52 INFO - 'sessionrestore', 05:57:52 INFO - 'sessionrestore_no_auto_restore', 05:57:52 INFO - 'tabpaint']}, 05:57:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:52 INFO - 'ts_paint', 05:57:52 INFO - 'tpaint', 05:57:52 INFO - 'sessionrestore', 05:57:52 INFO - 'sessionrestore_no_auto_restore', 05:57:52 INFO - 'tabpaint']}, 05:57:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['a11yr', 05:57:52 INFO - 'ts_paint', 05:57:52 INFO - 'tpaint', 05:57:52 INFO - 'sessionrestore', 05:57:52 INFO - 'sessionrestore_no_auto_restore', 05:57:52 INFO - 'tabpaint']}, 05:57:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['a11yr', 05:57:52 INFO - 'ts_paint', 05:57:52 INFO - 'tpaint', 05:57:52 INFO - 'sessionrestore', 05:57:52 INFO - 'sessionrestore_no_auto_restore', 05:57:52 INFO - 'tabpaint']}, 05:57:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['tsvgx', 05:57:52 INFO - 'tsvgr_opacity', 05:57:52 INFO - 'tart', 05:57:52 INFO - 'tscrollx', 05:57:52 INFO - 'cart']}, 05:57:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:52 INFO - 'tsvgr_opacity', 05:57:52 INFO - 'tart', 05:57:52 INFO - 'tscrollx', 05:57:52 INFO - 'cart']}, 05:57:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'talos_options': ['--disable-e10s'], 05:57:52 INFO - 'tests': ['tp5o']}, 05:57:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'tests': ['tp5o']}, 05:57:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'talos_options': ['--disable-e10s', 05:57:52 INFO - '--xperf_path', 05:57:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:52 INFO - 'tests': ['tp5n']}, 05:57:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:52 INFO - 'talos_options': ['--xperf_path', 05:57:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:52 INFO - 'tests': ['tp5n']}}, 05:57:52 INFO - 'talos.zip': {'path': '', 05:57:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:52 INFO - Running post-action listener: _resource_record_post_action 05:57:52 INFO - ##### 05:57:52 INFO - ##### Finished populate-webroot step (success) 05:57:52 INFO - ##### 05:57:52 INFO - ##### 05:57:52 INFO - ##### Running create-virtualenv step. 05:57:52 INFO - ##### 05:57:52 INFO - Running pre-action listener: _resource_record_pre_action 05:57:52 INFO - Running main action method: create_virtualenv 05:57:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:52 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 05:57:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:52 INFO - Using real prefix '/usr' 05:57:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:54 INFO - Installing pip.................done. 05:57:54 INFO - Return code: 0 05:57:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57: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')]} 05:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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')]} 05:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:54 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:57:54 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 05:57:55 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 05:57:55 INFO - Using env: {'DISPLAY': ':0', 05:57:55 INFO - 'HOME': '/home/cltbld', 05:57:55 INFO - 'LANG': 'en_US.UTF-8', 05:57:55 INFO - 'LANGUAGE': 'en_US:en', 05:57:55 INFO - 'LOGNAME': 'cltbld', 05:57:55 INFO - 'MAIL': '/var/mail/cltbld', 05:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:55 INFO - 'MOZ_NO_REMOTE': '1', 05:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:55 INFO - 'NO_EM_RESTART': '1', 05:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:55 INFO - 'PWD': '/builds/slave/test', 05:57:55 INFO - 'SHELL': '/bin/bash', 05:57:55 INFO - 'SHLVL': '1', 05:57:55 INFO - 'TERM': 'linux', 05:57:55 INFO - 'TMOUT': '86400', 05:57:55 INFO - 'USER': 'cltbld', 05:57:55 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:55 INFO - '_': '/tools/buildbot/bin/python'} 05:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:55 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 05:57:55 INFO - Cleaning up... 05:57:55 INFO - Return code: 0 05:57:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:55 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:57:55 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 05:57:55 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 05:57:55 INFO - Using env: {'DISPLAY': ':0', 05:57:55 INFO - 'HOME': '/home/cltbld', 05:57:55 INFO - 'LANG': 'en_US.UTF-8', 05:57:55 INFO - 'LANGUAGE': 'en_US:en', 05:57:55 INFO - 'LOGNAME': 'cltbld', 05:57:55 INFO - 'MAIL': '/var/mail/cltbld', 05:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:55 INFO - 'MOZ_NO_REMOTE': '1', 05:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:55 INFO - 'NO_EM_RESTART': '1', 05:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:55 INFO - 'PWD': '/builds/slave/test', 05:57:55 INFO - 'SHELL': '/bin/bash', 05:57:55 INFO - 'SHLVL': '1', 05:57:55 INFO - 'TERM': 'linux', 05:57:55 INFO - 'TMOUT': '86400', 05:57:55 INFO - 'USER': 'cltbld', 05:57:55 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:55 INFO - '_': '/tools/buildbot/bin/python'} 05:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:55 INFO - Downloading/unpacking psutil>=3.1.1 05:57:55 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 05:57:55 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 05:57:55 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 05:57:55 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 05:57:55 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 05:57:55 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 05:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:57 INFO - Installing collected packages: psutil 05:57:57 INFO - Running setup.py install for psutil 05:57:57 INFO - building 'psutil._psutil_linux' extension 05:57:57 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 05:57:57 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 05:57:57 INFO - building 'psutil._psutil_posix' extension 05:57:57 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 05:57:57 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 05:57:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:57 INFO - Successfully installed psutil 05:57:57 INFO - Cleaning up... 05:57:57 INFO - Return code: 0 05:57:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57: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')]} 05:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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')]} 05:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:57 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:57:57 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 05:57:57 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 05:57:57 INFO - Using env: {'DISPLAY': ':0', 05:57:57 INFO - 'HOME': '/home/cltbld', 05:57:57 INFO - 'LANG': 'en_US.UTF-8', 05:57:57 INFO - 'LANGUAGE': 'en_US:en', 05:57:57 INFO - 'LOGNAME': 'cltbld', 05:57:57 INFO - 'MAIL': '/var/mail/cltbld', 05:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:57 INFO - 'MOZ_NO_REMOTE': '1', 05:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:57 INFO - 'NO_EM_RESTART': '1', 05:57:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:57 INFO - 'PWD': '/builds/slave/test', 05:57:57 INFO - 'SHELL': '/bin/bash', 05:57:57 INFO - 'SHLVL': '1', 05:57:57 INFO - 'TERM': 'linux', 05:57:57 INFO - 'TMOUT': '86400', 05:57:57 INFO - 'USER': 'cltbld', 05:57:57 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:57 INFO - '_': '/tools/buildbot/bin/python'} 05:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:58:00 INFO - Installing collected packages: mozsystemmonitor 05:58:00 INFO - Running setup.py install for mozsystemmonitor 05:58:00 INFO - Successfully installed mozsystemmonitor 05:58:00 INFO - Cleaning up... 05:58:00 INFO - Return code: 0 05:58:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:58:00 INFO - Using env: {'DISPLAY': ':0', 05:58:00 INFO - 'HOME': '/home/cltbld', 05:58:00 INFO - 'LANG': 'en_US.UTF-8', 05:58:00 INFO - 'LANGUAGE': 'en_US:en', 05:58:00 INFO - 'LOGNAME': 'cltbld', 05:58:00 INFO - 'MAIL': '/var/mail/cltbld', 05:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:00 INFO - 'MOZ_NO_REMOTE': '1', 05:58:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:00 INFO - 'NO_EM_RESTART': '1', 05:58:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:00 INFO - 'PWD': '/builds/slave/test', 05:58:00 INFO - 'SHELL': '/bin/bash', 05:58:00 INFO - 'SHLVL': '1', 05:58:00 INFO - 'TERM': 'linux', 05:58:00 INFO - 'TMOUT': '86400', 05:58:00 INFO - 'USER': 'cltbld', 05:58:00 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:00 INFO - '_': '/tools/buildbot/bin/python'} 05:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:58:00 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 05:58:00 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 05:58:00 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 05:58:00 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 05:58:00 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 05:58:00 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 05:58:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:58:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:58:02 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 05:58:02 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 05:58:02 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 05:58:02 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 05:58:02 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 05:58:02 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 05:58:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:58:02 INFO - warning: no files found matching '*.txt' 05:58:02 INFO - no previously-included directories found matching 'build' 05:58:02 INFO - no previously-included directories found matching 'dist' 05:58:02 INFO - no previously-included directories found matching '.git*' 05:58:02 INFO - Installing collected packages: jsonschema, functools32 05:58:02 INFO - Running setup.py install for functools32 05:58:02 INFO - warning: no files found matching '*.txt' 05:58:02 INFO - no previously-included directories found matching 'build' 05:58:02 INFO - no previously-included directories found matching 'dist' 05:58:02 INFO - no previously-included directories found matching '.git*' 05:58:02 INFO - Successfully installed jsonschema functools32 05:58:02 INFO - Cleaning up... 05:58:02 INFO - Return code: 0 05:58:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:02 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:02 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 05:58:02 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 05:58:02 INFO - Using env: {'DISPLAY': ':0', 05:58:02 INFO - 'HOME': '/home/cltbld', 05:58:02 INFO - 'LANG': 'en_US.UTF-8', 05:58:02 INFO - 'LANGUAGE': 'en_US:en', 05:58:02 INFO - 'LOGNAME': 'cltbld', 05:58:02 INFO - 'MAIL': '/var/mail/cltbld', 05:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:02 INFO - 'MOZ_NO_REMOTE': '1', 05:58:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:02 INFO - 'NO_EM_RESTART': '1', 05:58:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:02 INFO - 'PWD': '/builds/slave/test', 05:58:02 INFO - 'SHELL': '/bin/bash', 05:58:02 INFO - 'SHLVL': '1', 05:58:02 INFO - 'TERM': 'linux', 05:58:02 INFO - 'TMOUT': '86400', 05:58:02 INFO - 'USER': 'cltbld', 05:58:02 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:02 INFO - '_': '/tools/buildbot/bin/python'} 05:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:03 INFO - Downloading/unpacking blobuploader==1.2.4 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:58:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:58:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 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 05:58:05 INFO - Downloading docopt-0.6.1.tar.gz 05:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:58:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:58:05 INFO - Running setup.py install for blobuploader 05:58:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:58:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:58:05 INFO - Running setup.py install for requests 05:58:06 INFO - Running setup.py install for docopt 05:58:06 INFO - Successfully installed blobuploader requests docopt 05:58:06 INFO - Cleaning up... 05:58:06 INFO - Return code: 0 05:58:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58: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')]} 05:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58: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')]} 05:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:06 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:06 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 05:58:06 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 05:58:06 INFO - Using env: {'DISPLAY': ':0', 05:58:06 INFO - 'HOME': '/home/cltbld', 05:58:06 INFO - 'LANG': 'en_US.UTF-8', 05:58:06 INFO - 'LANGUAGE': 'en_US:en', 05:58:06 INFO - 'LOGNAME': 'cltbld', 05:58:06 INFO - 'MAIL': '/var/mail/cltbld', 05:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:06 INFO - 'MOZ_NO_REMOTE': '1', 05:58:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:06 INFO - 'NO_EM_RESTART': '1', 05:58:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:06 INFO - 'PWD': '/builds/slave/test', 05:58:06 INFO - 'SHELL': '/bin/bash', 05:58:06 INFO - 'SHLVL': '1', 05:58:06 INFO - 'TERM': 'linux', 05:58:06 INFO - 'TMOUT': '86400', 05:58:06 INFO - 'USER': 'cltbld', 05:58:06 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:06 INFO - '_': '/tools/buildbot/bin/python'} 05:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:06 INFO - Running setup.py (path:/tmp/pip-5oY44H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:06 INFO - Running setup.py (path:/tmp/pip-GMUPbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:06 INFO - Running setup.py (path:/tmp/pip-FfKjFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:06 INFO - Running setup.py (path:/tmp/pip-1qsV6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:06 INFO - Running setup.py (path:/tmp/pip-aRKmjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:06 INFO - Running setup.py (path:/tmp/pip-iljZKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:06 INFO - Running setup.py (path:/tmp/pip-xFUCU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:07 INFO - Running setup.py (path:/tmp/pip-SMPQBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:07 INFO - Running setup.py (path:/tmp/pip-5vcXLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:07 INFO - Running setup.py (path:/tmp/pip-j6fqNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:07 INFO - Running setup.py (path:/tmp/pip-TfMd7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:07 INFO - Running setup.py (path:/tmp/pip-WQh6tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:07 INFO - Running setup.py (path:/tmp/pip-qTsBp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:07 INFO - Running setup.py (path:/tmp/pip-4Y7HeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:07 INFO - Running setup.py (path:/tmp/pip-Fi_WRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:07 INFO - Running setup.py (path:/tmp/pip-6MCdn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:07 INFO - Running setup.py (path:/tmp/pip-_5J9_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:58:07 INFO - Running setup.py install for manifestparser 05:58:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:58:07 INFO - Running setup.py install for mozcrash 05:58:07 INFO - Running setup.py install for mozdebug 05:58:08 INFO - Running setup.py install for mozdevice 05:58:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Running setup.py install for mozfile 05:58:08 INFO - Running setup.py install for mozhttpd 05:58:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Running setup.py install for mozinfo 05:58:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Running setup.py install for mozInstall 05:58:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Running setup.py install for mozleak 05:58:08 INFO - Running setup.py install for mozlog 05:58:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Running setup.py install for moznetwork 05:58:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:58:08 INFO - Running setup.py install for mozprocess 05:58:08 INFO - Running setup.py install for mozprofile 05:58:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:58:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:58:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:58:09 INFO - Running setup.py install for mozrunner 05:58:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:58:09 INFO - Running setup.py install for mozscreenshot 05:58:09 INFO - Running setup.py install for moztest 05:58:09 INFO - Running setup.py install for mozversion 05:58:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:58:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:58:09 INFO - Cleaning up... 05:58:09 INFO - Return code: 0 05:58:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:09 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')]} 05:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:09 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')]} 05:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:09 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:09 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 05:58:09 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 05:58:09 INFO - Using env: {'DISPLAY': ':0', 05:58:09 INFO - 'HOME': '/home/cltbld', 05:58:09 INFO - 'LANG': 'en_US.UTF-8', 05:58:09 INFO - 'LANGUAGE': 'en_US:en', 05:58:09 INFO - 'LOGNAME': 'cltbld', 05:58:09 INFO - 'MAIL': '/var/mail/cltbld', 05:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:09 INFO - 'MOZ_NO_REMOTE': '1', 05:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:09 INFO - 'NO_EM_RESTART': '1', 05:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:09 INFO - 'PWD': '/builds/slave/test', 05:58:09 INFO - 'SHELL': '/bin/bash', 05:58:09 INFO - 'SHLVL': '1', 05:58:09 INFO - 'TERM': 'linux', 05:58:09 INFO - 'TMOUT': '86400', 05:58:09 INFO - 'USER': 'cltbld', 05:58:09 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:09 INFO - '_': '/tools/buildbot/bin/python'} 05:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:09 INFO - Running setup.py (path:/tmp/pip-D1lUgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:09 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)) 05:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:09 INFO - Running setup.py (path:/tmp/pip-uoTUxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:09 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)) 05:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:09 INFO - Running setup.py (path:/tmp/pip-_XXHn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:09 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)) 05:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:09 INFO - Running setup.py (path:/tmp/pip-ZHb89d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:10 INFO - Running setup.py (path:/tmp/pip-daaEtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:10 INFO - Running setup.py (path:/tmp/pip-Ot17Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:10 INFO - Running setup.py (path:/tmp/pip-dpzi2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:10 INFO - Running setup.py (path:/tmp/pip-85XRa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:10 INFO - Running setup.py (path:/tmp/pip-RBrPch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:10 INFO - Running setup.py (path:/tmp/pip-SYlFNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:10 INFO - Running setup.py (path:/tmp/pip-hD_S_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:10 INFO - Running setup.py (path:/tmp/pip-FR58G5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:10 INFO - Running setup.py (path:/tmp/pip-qi5Bth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:10 INFO - Running setup.py (path:/tmp/pip-zV6Jiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:10 INFO - Running setup.py (path:/tmp/pip-8Oh70B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:10 INFO - Running setup.py (path:/tmp/pip-6xLmG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:10 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)) 05:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:10 INFO - Running setup.py (path:/tmp/pip-tA7Pf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:10 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)) 05:58:10 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)) 05:58:10 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)) 05:58:10 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)) 05:58:10 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)) 05:58:10 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)) 05:58:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58:12 INFO - Downloading blessings-1.6.tar.gz 05:58:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:58:12 INFO - Installing collected packages: blessings 05:58:12 INFO - Running setup.py install for blessings 05:58:13 INFO - Successfully installed blessings 05:58:13 INFO - Cleaning up... 05:58:13 INFO - Return code: 0 05:58:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:58:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:13 INFO - Reading from file tmpfile_stdout 05:58:13 INFO - Current package versions: 05:58:13 INFO - argparse == 1.2.1 05:58:13 INFO - blessings == 1.6 05:58:13 INFO - blobuploader == 1.2.4 05:58:13 INFO - docopt == 0.6.1 05:58:13 INFO - functools32 == 3.2.3-2 05:58:13 INFO - jsonschema == 2.5.1 05:58:13 INFO - manifestparser == 1.1 05:58:13 INFO - mozInstall == 1.12 05:58:13 INFO - mozcrash == 0.17 05:58:13 INFO - mozdebug == 0.1 05:58:13 INFO - mozdevice == 0.48 05:58:13 INFO - mozfile == 1.2 05:58:13 INFO - mozhttpd == 0.7 05:58:13 INFO - mozinfo == 0.9 05:58:13 INFO - mozleak == 0.1 05:58:13 INFO - mozlog == 3.2 05:58:13 INFO - moznetwork == 0.27 05:58:13 INFO - mozprocess == 0.22 05:58:13 INFO - mozprofile == 0.28 05:58:13 INFO - mozrunner == 6.11 05:58:13 INFO - mozscreenshot == 0.1 05:58:13 INFO - mozsystemmonitor == 0.3 05:58:13 INFO - moztest == 0.7 05:58:13 INFO - mozversion == 1.4 05:58:13 INFO - psutil == 3.1.1 05:58:13 INFO - requests == 1.2.3 05:58:13 INFO - wsgiref == 0.1.2 05:58:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:13 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')]} 05:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:13 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')]} 05:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:13 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:13 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 05:58:13 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 05:58:13 INFO - Using env: {'DISPLAY': ':0', 05:58:13 INFO - 'HOME': '/home/cltbld', 05:58:13 INFO - 'LANG': 'en_US.UTF-8', 05:58:13 INFO - 'LANGUAGE': 'en_US:en', 05:58:13 INFO - 'LOGNAME': 'cltbld', 05:58:13 INFO - 'MAIL': '/var/mail/cltbld', 05:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:13 INFO - 'MOZ_NO_REMOTE': '1', 05:58:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:13 INFO - 'NO_EM_RESTART': '1', 05:58:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:13 INFO - 'PWD': '/builds/slave/test', 05:58:13 INFO - 'SHELL': '/bin/bash', 05:58:13 INFO - 'SHLVL': '1', 05:58:13 INFO - 'TERM': 'linux', 05:58:13 INFO - 'TMOUT': '86400', 05:58:13 INFO - 'USER': 'cltbld', 05:58:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:13 INFO - '_': '/tools/buildbot/bin/python'} 05:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 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)) 05:58:13 INFO - Cleaning up... 05:58:13 INFO - Return code: 0 05:58:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:58:13 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')]} 05:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:13 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')]} 05:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:13 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:13 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 05:58:13 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 05:58:13 INFO - Using env: {'DISPLAY': ':0', 05:58:13 INFO - 'HOME': '/home/cltbld', 05:58:13 INFO - 'LANG': 'en_US.UTF-8', 05:58:13 INFO - 'LANGUAGE': 'en_US:en', 05:58:13 INFO - 'LOGNAME': 'cltbld', 05:58:13 INFO - 'MAIL': '/var/mail/cltbld', 05:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:13 INFO - 'MOZ_NO_REMOTE': '1', 05:58:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:13 INFO - 'NO_EM_RESTART': '1', 05:58:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:13 INFO - 'PWD': '/builds/slave/test', 05:58:13 INFO - 'SHELL': '/bin/bash', 05:58:13 INFO - 'SHLVL': '1', 05:58:13 INFO - 'TERM': 'linux', 05:58:13 INFO - 'TMOUT': '86400', 05:58:13 INFO - 'USER': 'cltbld', 05:58:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:13 INFO - '_': '/tools/buildbot/bin/python'} 05:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:58:13 INFO - Cleaning up... 05:58:13 INFO - Return code: 0 05:58:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:58:13 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')]} 05:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:13 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')]} 05:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:13 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 0x7f413bf30f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, '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': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', '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 05:58:13 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 05:58:13 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 05:58:13 INFO - Using env: {'DISPLAY': ':0', 05:58:13 INFO - 'HOME': '/home/cltbld', 05:58:13 INFO - 'LANG': 'en_US.UTF-8', 05:58:13 INFO - 'LANGUAGE': 'en_US:en', 05:58:13 INFO - 'LOGNAME': 'cltbld', 05:58:13 INFO - 'MAIL': '/var/mail/cltbld', 05:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:13 INFO - 'MOZ_NO_REMOTE': '1', 05:58:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:13 INFO - 'NO_EM_RESTART': '1', 05:58:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:13 INFO - 'PWD': '/builds/slave/test', 05:58:13 INFO - 'SHELL': '/bin/bash', 05:58:13 INFO - 'SHLVL': '1', 05:58:13 INFO - 'TERM': 'linux', 05:58:13 INFO - 'TMOUT': '86400', 05:58:13 INFO - 'USER': 'cltbld', 05:58:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:13 INFO - '_': '/tools/buildbot/bin/python'} 05:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:13 INFO - Downloading/unpacking flake8 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:58:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:58:15 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 05:58:15 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 05:58:15 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 05:58:15 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 05:58:15 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 05:58:15 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 05:58:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:58:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:58:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:58:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:58:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:58:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:58:16 INFO - no previously-included directories found matching 'docs/_build' 05:58:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:58:16 INFO - Running setup.py install for pep8 05:58:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:58:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:58:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:58:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:58:16 INFO - no previously-included directories found matching 'docs/_build' 05:58:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:58:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:58:16 INFO - Cleaning up... 05:58:16 INFO - Return code: 0 05:58:16 INFO - Running post-action listener: _resource_record_post_action 05:58:16 INFO - Running post-action listener: _start_resource_monitoring 05:58:16 INFO - Starting resource monitoring. 05:58:16 INFO - ##### 05:58:16 INFO - ##### Finished create-virtualenv step (success) 05:58:16 INFO - ##### 05:58:16 INFO - ##### 05:58:16 INFO - ##### Running install step. 05:58:16 INFO - ##### 05:58:16 INFO - Running pre-action listener: _resource_record_pre_action 05:58:16 INFO - Running main action method: install 05:58:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:17 INFO - Reading from file tmpfile_stdout 05:58:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:58:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:58:17 INFO - Reading from file tmpfile_stdout 05:58:17 INFO - Output received: 05:58:17 INFO - Usage: mozinstall [options] installer 05:58:17 INFO - Options: 05:58:17 INFO - -h, --help show this help message and exit 05:58:17 INFO - -d DEST, --destination=DEST 05:58:17 INFO - Directory to install application into. [default: 05:58:17 INFO - "/builds/slave/test"] 05:58:17 INFO - --app=APP Application being installed. [default: firefox] 05:58:17 INFO - mkdir: /builds/slave/test/build/application 05:58:17 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'] 05:58:17 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 05:58:33 INFO - Reading from file tmpfile_stdout 05:58:33 INFO - Output received: 05:58:33 INFO - /builds/slave/test/build/application/firefox/firefox 05:58:33 INFO - Running post-action listener: _resource_record_post_action 05:58:33 INFO - ##### 05:58:33 INFO - ##### Finished install step (success) 05:58:33 INFO - ##### 05:58:33 INFO - ##### 05:58:33 INFO - ##### Running run-tests step. 05:58:33 INFO - ##### 05:58:33 INFO - Running pre-action listener: _resource_record_pre_action 05:58:33 INFO - Running main action method: run_tests 05:58:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:33 INFO - Python 2.7.3 05:58:33 INFO - Return code: 0 05:58:33 INFO - grabbing minidump binary from tooltool 05:58:33 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')]} 05:58:33 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 0x1bee1c0>, '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 0x1bef0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bef550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:33 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 05:58:33 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 05:58:33 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 05:58:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:33 INFO - Return code: 0 05:58:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:58:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:58:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:58:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:58:34 INFO - Return code: 0 05:58:34 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/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 05:58:34 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/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 05:58:34 INFO - Using env: {'DISPLAY': ':0', 05:58:34 INFO - 'HOME': '/home/cltbld', 05:58:34 INFO - 'LANG': 'en_US.UTF-8', 05:58:34 INFO - 'LANGUAGE': 'en_US:en', 05:58:34 INFO - 'LOGNAME': 'cltbld', 05:58:34 INFO - 'MAIL': '/var/mail/cltbld', 05:58:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:34 INFO - 'MOZ_NO_REMOTE': '1', 05:58:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:34 INFO - 'NO_EM_RESTART': '1', 05:58:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:34 INFO - 'PWD': '/builds/slave/test', 05:58:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:34 INFO - 'SHELL': '/bin/bash', 05:58:34 INFO - 'SHLVL': '1', 05:58:34 INFO - 'TERM': 'linux', 05:58:34 INFO - 'TMOUT': '86400', 05:58:34 INFO - 'USER': 'cltbld', 05:58:34 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447', 05:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:34 INFO - '_': '/tools/buildbot/bin/python'} 05:58:34 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/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 05:58:34 INFO - mozversion application_buildid: 20160627025709 05:58:34 INFO - mozversion application_changeset: 9f311d92805addcd093ba46dabfcf385a0f7fed3 05:58:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:58:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:34 INFO - mozversion application_name: Firefox 05:58:34 INFO - mozversion application_remotingname: firefox-dev 05:58:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:58:34 INFO - mozversion application_vendor: Mozilla 05:58:34 INFO - mozversion application_version: 49.0a2 05:58:34 INFO - mozversion platform_buildid: 20160627025709 05:58:34 INFO - mozversion platform_changeset: 9f311d92805addcd093ba46dabfcf385a0f7fed3 05:58:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:58:34 INFO - mozversion platform_version: 49.0a2 05:58:34 INFO - using testdate: 1467032314 05:58:34 INFO - actual date: 1467032314 05:58:34 INFO - starting webserver on 'localhost:33643' 05:58:34 INFO - SUITE-START | Running 2 tests 05:58:34 INFO - TEST-START | dromaeo_css 05:58:34 INFO - operating with platform_type : linux_ 05:58:34 INFO - Initialising browser for dromaeo_css test... 05:58:34 INFO - TEST-INFO | started process 6498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU_sh5U/profile http://localhost:33643/getInfo.html) 05:58:38 INFO - PROCESS | 6498 | __metrics Screen width/height:1600/1200 05:58:38 INFO - PROCESS | 6498 | colorDepth:24 05:58:38 INFO - PROCESS | 6498 | Browser inner width/height: 1024/697 05:58:38 INFO - PROCESS | 6498 | __metrics 05:58:39 ERROR - PROCESS | 6498 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:58:39 INFO - PROCESS | 6498 | console.error: PushService: 05:58:39 INFO - PROCESS | 6498 | stateChangeProcessEnqueue: Error transitioning state 05:58:39 INFO - PROCESS | 6498 | UnknownError 05:58:40 INFO - TEST-INFO | 6498: exit 0 05:58:40 INFO - Browser initialized. 05:58:40 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:40 INFO - TEST-INFO | started process 6589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU_sh5U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:52 INFO - PROCESS | 6589 | RSS: Main: 145010688 05:58:52 INFO - PROCESS | 6589 | 05:59:43 INFO - PROCESS | 6589 | 1467032383296 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] 06:01:51 INFO - PROCESS | 6589 | Cycle 1(1): loaded http://localhost:33643/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33643/tests/dromaeo/cssquery-ext.html) 06:01:52 INFO - PROCESS | 6589 | RSS: Main: 250769408 06:01:52 INFO - PROCESS | 6589 | 06:04:54 INFO - PROCESS | 6589 | Cycle 1(1): loaded http://localhost:33643/tests/dromaeo/cssquery-ext.html (next: http://localhost:33643/tests/dromaeo/cssquery-jquery.html) 06:04:55 INFO - PROCESS | 6589 | RSS: Main: 229896192 06:04:55 INFO - PROCESS | 6589 | 06:07:57 INFO - PROCESS | 6589 | Cycle 1(1): loaded http://localhost:33643/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33643/tests/dromaeo/cssquery-mootools.html) 06:07:58 INFO - PROCESS | 6589 | RSS: Main: 226115584 06:07:58 INFO - PROCESS | 6589 | 06:10:59 INFO - PROCESS | 6589 | Cycle 1(1): loaded http://localhost:33643/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33643/tests/dromaeo/cssquery-prototype.html) 06:11:00 INFO - PROCESS | 6589 | RSS: Main: 222068736 06:11:00 INFO - PROCESS | 6589 | 06:14:03 INFO - PROCESS | 6589 | Cycle 1(1): loaded http://localhost:33643/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33643/tests/dromaeo/cssquery-yui.html) 06:14:03 INFO - PROCESS | 6589 | RSS: Main: 217788416 06:14:03 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | Cycle 1(1): loaded http://localhost:33643/tests/dromaeo/cssquery-yui.html (next: http://localhost:33643/tests/dromaeo/cssquery-dojo.html) 06:17:06 INFO - PROCESS | 6589 | RSS: Main: 258883584 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | __start_tp_report 06:17:06 INFO - PROCESS | 6589 | _x_x_mozilla_page_load 06:17:06 INFO - PROCESS | 6589 | _x_x_mozilla_page_load_details 06:17:06 INFO - PROCESS | 6589 | |i|pagename|runs| 06:17:06 INFO - PROCESS | 6589 | |0;dojo.html;3341.715954143898;3363.360961417332;3372.6964573188407;3406.4890266460043;3418.8974330770084;5520.91718624221;5560.77756889724;5590.972045139773;5604.01929662309;5609.635373700717;5470.863228419282;5511.862203444916;5524.502794748471;5525.336959564847;5551.861203469915;4953.306537084811;4988.825391111302;5007.924881126786;5044.39467263927;5082.186850103984;4880.707157570557;5004.349434573501;5039.571636410909;5040.16837221858;5051.9494805051845;5043.36957880265;5064.215046667772;5090.618203634724;5099.541041306282;5119.820806271781;5159.71621560814;5176.120059589879;5182.300389447414;5184.326037615105;5202.8699282518055;4874.561289483963;4937.061958227961;4970;5073;5095.159298715707;5355.973220133875;5403.729813509348;5431.755570999275;5451.890962180773;5511.338639363262;5953.166556682068;5954.07711804671;5962.25471816023;5964.880702385972;5971.910421343683;5888.911666325009;5926.555508336849;5932.822015339503;5950;5996.43033911782;5567.053600887859;5586.748596313176;5593.748281327309;5609.186667933127;5621.522170615503;5884.08796636522;6083.501986357862;6084.113924683363;6119;6129.11127886454;5757.481826635539;5788.479036886701;5833.912491312633;5844.444777746107;5873.353931067579;5925.111233314951;5952.76188952437;5956.404359564009;5961.344252132262;5968.7015649217365;5760.36635970043;5853.765849366063;5879.735411906475;5886.175935369051;5887.175795388649;5886.941130588725;5913.852153696192;5950;5959.195508606283;5995.130706047598;4548.931766023512;4554.339620754972;4554.362389265505;4559.680822342404;4572.359869618256;4012.337964235927;4017.2367250222364;4047.0691740899756;4058.9188216236103;4100.466939297872;5684.37471878093;5705.429457054262;5710.885782284414;5713.771449141987;5733.3406658234;5918.8816223675285;5920.674362910081;5924;5985.491233245136;6028.788992385245;5496.615236933376;5542.418046105182;5557.027520184032;5558.610897237155;5650.63270887391;5108.055009823194;5120.257562653469;5124.718140502234;5125.948740512622;5138.8972220556125;6168.25980882297;6170.136180934673;6179.412955769195;6186.845328866814;6191.071339299141;5799.594028417971;5933.02105152645;5935.851603709942;5968.850778730566;5989.790357337472;6184.350643182401;6200.3489633589625;6200.720967556561;6212.47193988516;6214.316425193316;5888.911666325094;5889.617174883619;5891.469767720754;5892.292924849045;5924.674142922008;5794.478496935296;5897.233359663216;5919.289685237799;5931.555133364928;5960.701964901824;5778.335491418541;5803.100519419497;5817.418258174149;5823.330317013598;5831.037878750129;5764.423557644202;5810.767569297181;5820.505257053198;5824.970875145597;5825.097109947965;5532.972335138459;5681.0910254359105;5728.427157284238;5740.540756739534;5784.190213370048;5893.823185304448;5909.704514774158;5935.258092738408;5940.108983652487;5940.910886336622;5611.971940140436;5783.363829978614;5786.594938354275;5787.39232380594;5813.011787995966 06:17:06 INFO - PROCESS | 6589 | |1;ext.html;585.1982783586487;642.3672682407826;643.3020173112174;653.2879161713734;664.8105289992353;34121;37338.62661373385;46407.535924640746;47320.053598928;47485;48176.518234817726;48807.755961220144;48809.51190488094;48898.75550622251;48943.51056489434;8977.640894364235;9127.543622818866;9132.589033493476;9149.405288656217;9159.587818548149;8473.449225800303;10626.14990800734;11013.28413653114;11020.779584408307;11329.660110196666;11045.502952367122;11119.833202501968;11132.220744547885;11137.164712646543;11138.108951283919;8404.915950840474;11028.28316159451;11107.944460277648;11112.888871111307;11155.330680159217;10606.257561970666;10673.092787113104;10937.83593246102;10947.890521094847;10960.835587466194;44886.55113448857;45305;45443.31835022477;45479.772601136785;45529.317060244124;3680.1351682354893;3801.239752049601;3833.195029043904;3945.171513982067;4398.34024896265;33333.83333083343;33707;33895.661043389744;34076.82961585176;34265;4550;4771.785269662873;4774.665773395864;4788.162071637449;4859.902801943977;353250;354223;355285.22357388306;356128;356561;69732.3026769736;70315.64842175858;70353;70382.2961770386;70438;40761.184776304304;41615.58384416117;41778.79110604488;43341.56658433438;73103.63448182725;18419.72370414445;18493.7225941611;18555.72166417505;18593.256269749058;18609.162587683586;12960.157589756636;13490;13713.245771482665;13808.240546770025;14036.368363423671;22320.665190022162;22403.55192896133;22425.99083041267;22431.55136897253;22500.887495562416;8007.357572001226;8143.79640508992;8152.959235203786;8252.793680158044;8386.70006149048;951.6526467839327;1059.6238335390979;1105.8617672790901;1124.797536443448;1252.3675543850297;33944;34087.65912340894;34252.828735856165;34742.65257347394;34771.65228347735;1127.6560649002133;1167.497975870384;1200.0819373179525;1200.7598480303975;1208.8307636930838;17909.373171938918;17989.910050449664;17996;18097;18234.270629174684;546520;550123;552123;553112;554317;63745.36254637487;63766;63850.36149638537;63965.68017159885;64502.35497645057;1347.198416941918;1464.2385959301105;1466.8459811719829;1483.1397789282191;1490.292111247149;32580;34039.31921361609;35147.82426087853;35406.822965885;35432;54151.45848541622;54347.45652543424;54648.180277295076;54746;54763.45236547584;414192;415088;415560.53318400495;416713;417867;28189.859050704614;30481.847590761903;31624.841875790473;31722;32744.836275818467;51041;51750.223746643074;52079.218811717095;52124.47875521196;52890.47109528856;44275.55724442843;44782.77608611936;44886.77556612196;45053.77473112744;46018.76990615026;43316;43593;44019.55980440155;44514.3322850151;44709.10581788281;42653.933651658954;43200.13599728051;43413.782931085145;43908.341374880045;43910;36906.44640330344;39851.60148398479;40602.1879562413;40652.59347406488;40857.79571102125;28793;30931.536026959162;31019.379612408076;31140.22149446191;31241.37517249597 06:17:06 INFO - PROCESS | 6589 | |2;jquery.html;433.7010653093984;434.941282926805;435.61012893460344;441.9624331931786;450.29079200259577;18994.240230390784;20168.69746953795;20739.792602073976;21308;21320.680189797167;19819.306324278652;20004.999750012514;20352.796472035276;20363.185472581117;20379.898100509476;5788.913166302509;6064.696765161724;6085.939140608603;6100.877982440348;6104.328523862371;4107.568705285947;5767.971160144193;5804.593678442489;6015.368386319439;6055.394460553954;6075.878482430348;6132.233470816148;6134.5092392608485;6166.4450199482035;6173.938260617404;4100.5899410059055;5759.798407055754;6056.757729690829;6066.907956567816;6099.298580663214;7007.684654190522;7380.151282602462;7394.445416593777;7400.074990626172;7500.512466689648;24355.87822060896;25694;26792;27115.45769084627;27881.442371152465;2575.266049175995;2595.4549544595657;2631.907883224078;2836.0357478457217;3004.8046876952926;15593.220338983005;15816.841831581653;15853.384640723489;15991.52025439227;17097.743533847002;2136.658134698441;2144.153059541488;2181.7490988536365;2210.6573481110454;2297.1041293895396;92433;98040.99999999929;98471;100555;101397;17683.73474397885;18182.36361727333;18900.338488152847;18962.43112706621;19154.904225478782;34025.48961765575;34102.317953641286;34407.483887741706;34535.654643453745;34569.135771605914;9373.140493835655;9738.853917191247;9743.074407931235;9993.150582200595;10108.898911010796;7977.60111994398;8134;8154.083790309272;8209;8271.2142346477;9412.858807117898;9434;9469.479178645102;9482.431054136636;9621.326507144433;3394.825837841626;3405.3131270777367;3479.6172421033666;3626.6192049834917;3680.871169509108;966.1884017425397;983.8475036369374;1045.5869931377063;1083.7561548651647;1114.6154576671036;19401.902990484956;20200.09099590522;20246;20328.186872525228;20599.794002060087;997.805427941559;1012.5342342522522;1030.3457304611625;1065.9786804263872;1067.7044354637437;10762.192835537366;11168.553257869595;11210.383428911487;11460.828087578693;11466.827997580043;369000;369197;374532;482292;504868;90387.54806225927;92589.5370523143;96048;97112.51443742737;97612.51193743986;1075.0485820049312;1089.6131873185063;1108.83921831334;1113.6993011886746;1122.38829837737;18181.818181818013;18303.26786928508;18420.815791842448;18434.72347914809;18674.25302987865;40050;40063;41167.1766564673;42064.369034463896;42750;59358.10962835648;60716;60945.69527152336;61014.084788729095;61507.6924615392;7907.48601340911;8077.1115177329275;8323.916760832557;8364.121767214356;9058.230050445522;9958.800823983625;13858.029937904654;13941.960442865733;13944.930275348559;14390.568282951528;6110.26676798787;9618.326717130014;11098.167637427065;11824.23142495735;12350;5810.360860305278;9735.490998895182;11025.117990560897;11348.943255283672;11501.769964600828;11724.58963936258;12045;12555.121141520005;12619.558315458915;12847.614571562868;7780.221977802401;8247.340212783083;8389;8924.509151996703;9393.718188454357;9449.480278584744;11446.198766086294;11914.463849126636;11990.280583165038;12446.751064978469 06:17:06 INFO - PROCESS | 6589 | |3;mootools.html;1428.4786053090625;1595.752658337958;1637.7870876786017;1859.4049904030708;1987.7018447232913;9112.863307050391;9499.952500237498;9582.329236953416;9861.60553577856;9888;9545.952270238638;9775.608975640966;9776.169025632811;9779.706608801729;9798.265130115235;2515.811314151437;2535.2267558394683;2537.5178716043984;2550.7194208637034;2554.642350070982;2485.2047344849657;2540.161746623619;2547.121243171103;2547.1339744486872;2550.311415917701;2525.103588226181;2525.5201511712808;2551.336652470362;2557.488502299538;2557.5907854743245;2443.523512914991;2461.9876900615473;2473.9134130305442;2474.6040633498733;2477.578811602032;2354.09367393553;2358.952820943589;2362.444825466014;2370.573296806574;2445.2786428003783;8969.416987895765;9213.401128926665;9730.513474326255;9746.46394448305;9779.266555008402;5431.375391829951;5624.465675760796;5626.746796394172;5627.521660658849;5630.746616402272;3641.47198656196;3750.362438385481;3772.9811350943346;3887.4168874668744;3889.649931506178;1251.555697727303;1274.222724138275;1301.3168086754436;1309.5940258519795;1323.543377534749;13826.861731382758;14020.579382618538;14078.507252246121;14086.788698169536;14105.717885642229;5058.291839142449;5144.61415393847;5172.844814655567;5173.663711858717;5259.132243179909;11657.125715571367;11709;11768.35274059935;11794.705132371588;11796.88203117975;5645.5201307888465;5682.516986056149;5707.229524014205;5716.171155182558;5728.541716662657;4590.219662657356;4632.2356811126465;5120.590352771769;5126.743662816919;5188.377394712583;4280.5505421930875;4292.313229883203;4307.375430562551;4354.194474022316;4380.342948557742;1357.4931825673495;1375.690469644322;1380.4547203854422;1381.3162484570203;1396.1692792788274;863.0299187007977;951.5955718819474;1061.7239517725448;1077.7628921637227;1084.815581351172;6411.198600174978;6508.219013718384;6753.08833307507;6772.729090836311;6780.796576102724;508.351851389474;509.83940058881336;513.8792383789806;515.0960065085751;672.0691841799144;4474.351219073216;4572.245579479415;4583.175028494837;4583.679142459996;4605.148047611203;11765.470553825098;12295.508179672892;12298.139130260795;12311.19977201479;12327.876721232673;11678.70803229926;12154.270743755404;12181.573644922562;12200.938995305145;12601;8960.462372257685;9024.819503609891;9092.363534552518;9106.95446522763;9119.407238529342;6462.321456247027;6578;6672.766453174116;6817.079694241413;6853.143357080365;10615.1507879371;10750.354978701303;11039.889601103887;11501.597444089752;11534.1926065178;11180.608678696206;11234.887651123385;11402.543898243977;11403.600873969372;11525.711857203636;7592.202818703957;8205.056418511947;8304.211099945496;8372.120927302545;8558.358123140664;9566.234701223746;9568.282378821756;9606.231501480004;9636.42181469114;9653.662121825702;8327.833443331221;8525.573721314033;8592.183742544448;8600.440971336842;8681.565921703763;8493;8501.574921253788;8512.02111757156;8592.957035214784;8609.526476043628;8013.8397232056195;8087.1104178539135;8090.595470226347;8093.676252949816;8121.4721043132;5563.193336966036;5680.346760122638;5767.5674324425;5887.322957859729;5915.70421478933;5733.942660573341;5899.174115623902;5933.881322373615;5972.552058595709;6298.622082675054 06:17:06 INFO - PROCESS | 6589 | |4;prototype.html;351.1397077160955;354.77649081078926;362.8802495176592;365.63071297989023;370.5738400839033;5414.512693857552;5437.673739575628;5542.140968149938;5615.971920140399;5646.8588285292835;5443.346798384199;5455;5467.9453205468035;5481.287432633752;5498.285222921025;4267.423897773791;4299.721147791675;4309.956900430987;4318.956810431902;4342.54403287655;4093.751405821238;4234.724742891703;4342.457192850894;4347.804348804311;4351;4289.935650965238;4311.33174357975;4319.611234988851;4324.805383757738;4326;4255.978720106411;4288.678349123828;4297.312430011199;4301.204277208695;4309.84915527955;3705.6664900159117;3820.4078367853026;3825.5600605930395;3842.5965273646143;3854.7494412863352;5242.816501422432;5277.894442111136;5287;5307.495787900143;5307.946920530784;4869.172240719086;4908.656394052417;4930.260460930853;4951.53929590768;4976.950230497685;5619.409961953977;5714.685692286923;5750.769969201227;5790.305163380379;5791.855203619901;4238.321868501025;4247.320428731388;4248.936265956013;4265.680073994432;4273.14118358515;5990.8502287442725;6000.309964354112;6004.519638428959;6006.399360064046;6011.699415029275;4251.9787401062795;4295.453636690789;4324.545922678137;4341.82632694695;4356.976110614003;4373.606375426227;4388.956110438919;4395.472543294825;4403.471583409947;4424.137293227854;4952.801887924515;4960.032793605213;4978.303037574742;4983.327250821165;5022.146235140035;5563.582731295088;5785.739641716049;5816.709164541756;5829.650220986754;5835.35811060783;1448.608875603581;1481.5036962617644;1503.225838693074;1521.1253529220742;1524.7941527893593;972.8929817720045;991.861139440479;1000.6997900629782;1001.2740762946805;1003.3177439341318;5025.748712564357;5057.393112826484;5067.290579318898;5093.8726531836255;5116.386033675983;5208.9739551302755;5244.505315984926;5257.239949026792;5286.5506431952945;5312.5484333832055;4734.928976065362;5032.572231360302;5053.69677819332;5063.3417655704525;5068.41713202979;2796.7063458336584;2824.491591513547;2834.8313540141808;2836.134978831476;2843.2607522044004;5378.462153784591;5461.672299662033;5546.505701408087;5549.667019978814;5561.360443548962;5198.818041368534;5345.679259244458;5364.785408583691;5447.945520544744;5464.371597266365;5410.891782164256;5420.268263784417;5430.42980487051;5485.232067510552;5490.6431081979545;5183.740812959261;5201.027534560513;5269.841904742864;5362.3833259174135;5371.248025276386;4954.5788607968725;5111.897762044812;5140.586338756872;5141.897162056813;5187.377514698261;5397.244385785914;5457.290552228185;5457.908418316432;5465.344158700981;5494.780208791603;3844.0966372902903;3985.820638071352;4006.338954072662;4010.6791456682813;4017.995501124719;5319.335083114611;5321.494458026481;5329.893402131858;5701.831124619526;5793.246877906014;5728.570357223141;5782.739776710142;5783.132530120515;5819.272590926134;5821.970890145522;5741;5775.826725198251;5789.102689083199;5799.130130480462;5812.604974805931;5541.667499949855;5662.688552129508;5685.232493613309;5693.4875861172695;5752.446839353267;5772.278465191851;5773.9895518284975;5791.884162316815;5803.506701930383;5823.272090988627;5537.944620553743;5724.627899186539;5739.282589676291;5742.684152371659;5779.855503612275 06:17:06 INFO - PROCESS | 6589 | |5;yui.html;741.0588552538273;755.4824944912732;799.6201804143033;840.7477756672997;852.1904191018532;15150.16674082925;16136.273867675953;16510;16516.83483165168;16976.15119244036;16863.156842157903;17390.043547604877;17410.56473588161;17421.91289043556;17422.128893555335;740.988885166723;861.8405594964952;891.505214605895;893.2228960804109;899.7660608241872;897.3673560140086;902.4044130873625;905.1627244798568;905.41148253635;905.6965916417975;941.0354386753598;941.3645789092369;942.8114377124568;943.3208090175098;943.79708362702;763.4770182425029;914.2457472585111;928.215657769184;928.8792456980617;929.5073610986221;688.686647575352;688.9242183359827;688.9931100689029;689.290031267795;689.4794430205213;16443.177841108016;16476.505704828873;16493.257803398876;16501.83498165027;16535.586610334838;14905.105693658415;14944.178070206135;15061.774073388908;15103.848961510354;15241;14064.648383790487;14166.92916535421;14199.645008874757;14237.857621423756;14550.927245363811;4670.182718024332;4723.527647235283;4757.26262429324;4806.711597304173;4816.56650901417;76386.61806690987;77072.22927770707;77435.61282193553;78178;78410;11964;12067.758644827054;12131.332776697367;12187.268763874195;12292.446839892047;21410.25064122748;21410.78589214119;21672.458188545414;21728.565428691338;21810.454738631342;8626.741197764077;8715.34634902372;8755.562221889008;9047.728568142966;9262.72211833646;4990.675606085593;5064.34163558735;5118.155504341742;5136.5377116059735;5200.76596553156;3791.6018818024263;3901.5708272090046;3907.6092390760696;3908.6286802753693;3918.4318273850704;616.8673735146965;656.0880376276979;658.7957733102753;737.71229220604;757.8484303139394;657.3163909534127;710.4848984486206;734.8273155808381;752.1312883619319;777.6306254529053;10152.593896244067;10263.332883362558;10459.320144190604;10778.78442431147;10803;698.9615571143535;744.8919906613511;782.7416952405692;793.4604468961046;814.1044850664222;4710;4733.715977041388;4756.8097276108565;4773.1408346497965;4849.078675051729;79040.60479697565;80015.59992200002;80307.59846200731;125049.37475312747;126963.22946825063;76410;104317;107598;108733;112516;95651;96107.51946240364;97544;98128;99493;8877.422967507091;9016.819663606822;9020;9080.863787043332;9302.069793020644;54961.725191373785;56841;56870.146947796646;56915.14627280511;57156.42843571511;74380.25619743734;82015;84241.57879210565;85222.72259778364;86008.56995714981;11742;11885.524579016743;12449.315287658903;12607.747845043232;12999.675008124872;14289.35697893618;14364.425422983382;14462.27688615544;14583.08338332359;14610;11809.409529523662;11945.283283003047;12706.428210730355;12709.8093528599;13856.376463058985;13122.737545249232;13293.401796918968;13329.533466328632;13425.664358390728;13920.443182272596;13761.862381376459;14097.718045639234;14125.858741412865;14617.049891757002;14660.193689347188;11066.889331106908;11097.889021110008;11135.220534562503;11211.103111750877;11532.192746507999;11300.265482743594;11793.233439826385;11859.525618975144;11985.460654270753;11988.400579971141 06:17:06 INFO - PROCESS | 6589 | __end_tp_report 06:17:06 INFO - PROCESS | 6589 | __start_cc_report 06:17:06 INFO - PROCESS | 6589 | _x_x_mozilla_cycle_collect,866 06:17:06 INFO - PROCESS | 6589 | __end_cc_report 06:17:06 INFO - PROCESS | 6589 | __startTimestamp1467033426529__endTimestamp 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | ------- Summary: start ------- 06:17:06 INFO - PROCESS | 6589 | Number of tests: 6 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | [#0] dojo.html Cycles:165 Average:5525.92 Median:5764.42 stddev:610.84 (10.6%) stddev-sans-first:588.19 06:17:06 INFO - PROCESS | 6589 | Values: 3341.7 3363.4 3372.7 3406.5 3418.9 5520.9 5560.8 5591.0 5604.0 5609.6 5470.9 5511.9 5524.5 5525.3 5551.9 4953.3 4988.8 5007.9 5044.4 5082.2 4880.7 5004.3 5039.6 5040.2 5051.9 5043.4 5064.2 5090.6 5099.5 5119.8 5159.7 5176.1 5182.3 5184.3 5202.9 4874.6 4937.1 4970.0 5073.0 5095.2 5356.0 5403.7 5431.8 5451.9 5511.3 5953.2 5954.1 5962.3 5964.9 5971.9 5888.9 5926.6 5932.8 5950.0 5996.4 5567.1 5586.7 5593.7 5609.2 5621.5 5884.1 6083.5 6084.1 6119.0 6129.1 5757.5 5788.5 5833.9 5844.4 5873.4 5925.1 5952.8 5956.4 5961.3 5968.7 5760.4 5853.8 5879.7 5886.2 5887.2 5886.9 5913.9 5950.0 5959.2 5995.1 4548.9 4554.3 4554.4 4559.7 4572.4 4012.3 4017.2 4047.1 4058.9 4100.5 5684.4 5705.4 5710.9 5713.8 5733.3 5918.9 5920.7 5924.0 5985.5 6028.8 5496.6 5542.4 5557.0 5558.6 5650.6 5108.1 5120.3 5124.7 5125.9 5138.9 6168.3 6170.1 6179.4 6186.8 6191.1 5799.6 5933.0 5935.9 5968.9 5989.8 6184.4 6200.3 6200.7 6212.5 6214.3 5888.9 5889.6 5891.5 5892.3 5924.7 5794.5 5897.2 5919.3 5931.6 5960.7 5778.3 5803.1 5817.4 5823.3 5831.0 5764.4 5810.8 5820.5 5825.0 5825.1 5533.0 5681.1 5728.4 5740.5 5784.2 5893.8 5909.7 5935.3 5940.1 5940.9 5612.0 5783.4 5786.6 5787.4 5813.0 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | [#1] ext.html Cycles:180 Average:61947.78 Median:40627.39 stddev:118543.83 (291.8%) stddev-sans-first:118786.84 06:17:06 INFO - PROCESS | 6589 | Values: 585.2 642.4 643.3 653.3 664.8 34121.0 37338.6 46407.5 47320.1 47485.0 48176.5 48807.8 48809.5 48898.8 48943.5 8977.6 9127.5 9132.6 9149.4 9159.6 8473.4 10626.1 11013.3 11020.8 11329.7 11045.5 11119.8 11132.2 11137.2 11138.1 8404.9 11028.3 11107.9 11112.9 11155.3 10606.3 10673.1 10937.8 10947.9 10960.8 44886.6 45305.0 45443.3 45479.8 45529.3 3680.1 3801.2 3833.2 3945.2 4398.3 33333.8 33707.0 33895.7 34076.8 34265.0 4550.0 4771.8 4774.7 4788.2 4859.9 353250.0 354223.0 355285.2 356128.0 356561.0 69732.3 70315.6 70353.0 70382.3 70438.0 40761.2 41615.6 41778.8 43341.6 73103.6 18419.7 18493.7 18555.7 18593.3 18609.2 12960.2 13490.0 13713.2 13808.2 14036.4 22320.7 22403.6 22426.0 22431.6 22500.9 8007.4 8143.8 8153.0 8252.8 8386.7 951.7 1059.6 1105.9 1124.8 1252.4 33944.0 34087.7 34252.8 34742.7 34771.7 1127.7 1167.5 1200.1 1200.8 1208.8 17909.4 17989.9 17996.0 18097.0 18234.3 546520.0 550123.0 552123.0 553112.0 554317.0 63745.4 63766.0 63850.4 63965.7 64502.4 1347.2 1464.2 1466.8 1483.1 1490.3 32580.0 34039.3 35147.8 35406.8 35432.0 54151.5 54347.5 54648.2 54746.0 54763.5 414192.0 415088.0 415560.5 416713.0 417867.0 28189.9 30481.8 31624.8 31722.0 32744.8 51041.0 51750.2 52079.2 52124.5 52890.5 44275.6 44782.8 44886.8 45053.8 46018.8 43316.0 43593.0 44019.6 44514.3 44709.1 42653.9 43200.1 43413.8 43908.3 43910.0 36906.4 39851.6 40602.2 40652.6 40857.8 28793.0 30931.5 31019.4 31140.2 31241.4 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | [#2] jquery.html Cycles:180 Average:29022.02 Median:19024.38 stddev:70752.95 (371.9%) stddev-sans-first:70918.87 06:17:06 INFO - PROCESS | 6589 | Values: 433.7 434.9 435.6 442.0 450.3 18994.2 20168.7 20739.8 21308.0 21320.7 19819.3 20005.0 20352.8 20363.2 20379.9 5788.9 6064.7 6085.9 6100.9 6104.3 4107.6 5768.0 5804.6 6015.4 6055.4 6075.9 6132.2 6134.5 6166.4 6173.9 4100.6 5759.8 6056.8 6066.9 6099.3 7007.7 7380.2 7394.4 7400.1 7500.5 24355.9 25694.0 26792.0 27115.5 27881.4 2575.3 2595.5 2631.9 2836.0 3004.8 15593.2 15816.8 15853.4 15991.5 17097.7 2136.7 2144.2 2181.7 2210.7 2297.1 92433.0 98041.0 98471.0 100555.0 101397.0 17683.7 18182.4 18900.3 18962.4 19154.9 34025.5 34102.3 34407.5 34535.7 34569.1 9373.1 9738.9 9743.1 9993.2 10108.9 7977.6 8134.0 8154.1 8209.0 8271.2 9412.9 9434.0 9469.5 9482.4 9621.3 3394.8 3405.3 3479.6 3626.6 3680.9 966.2 983.8 1045.6 1083.8 1114.6 19401.9 20200.1 20246.0 20328.2 20599.8 997.8 1012.5 1030.3 1066.0 1067.7 10762.2 11168.6 11210.4 11460.8 11466.8 369000.0 369197.0 374532.0 482292.0 504868.0 90387.5 92589.5 96048.0 97112.5 97612.5 1075.0 1089.6 1108.8 1113.7 1122.4 18181.8 18303.3 18420.8 18434.7 18674.3 40050.0 40063.0 41167.2 42064.4 42750.0 59358.1 60716.0 60945.7 61014.1 61507.7 7907.5 8077.1 8323.9 8364.1 9058.2 9958.8 13858.0 13942.0 13944.9 14390.6 6110.3 9618.3 11098.2 11824.2 12350.0 5810.4 9735.5 11025.1 11348.9 11501.8 11724.6 12045.0 12555.1 12619.6 12847.6 7780.2 8247.3 8389.0 8924.5 9393.7 9449.5 11446.2 11914.5 11990.3 12446.8 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | [#3] mootools.html Cycles:180 Average:6414.25 Median:2783.32 stddev:3728.07 (133.9%) stddev-sans-first:3719.70 06:17:06 INFO - PROCESS | 6589 | Values: 1428.5 1595.8 1637.8 1859.4 1987.7 9112.9 9500.0 9582.3 9861.6 9888.0 9546.0 9775.6 9776.2 9779.7 9798.3 2515.8 2535.2 2537.5 2550.7 2554.6 2485.2 2540.2 2547.1 2547.1 2550.3 2525.1 2525.5 2551.3 2557.5 2557.6 2443.5 2462.0 2473.9 2474.6 2477.6 2354.1 2359.0 2362.4 2370.6 2445.3 8969.4 9213.4 9730.5 9746.5 9779.3 5431.4 5624.5 5626.7 5627.5 5630.7 3641.5 3750.4 3773.0 3887.4 3889.6 1251.6 1274.2 1301.3 1309.6 1323.5 13826.9 14020.6 14078.5 14086.8 14105.7 5058.3 5144.6 5172.8 5173.7 5259.1 11657.1 11709.0 11768.4 11794.7 11796.9 5645.5 5682.5 5707.2 5716.2 5728.5 4590.2 4632.2 5120.6 5126.7 5188.4 4280.6 4292.3 4307.4 4354.2 4380.3 1357.5 1375.7 1380.5 1381.3 1396.2 863.0 951.6 1061.7 1077.8 1084.8 6411.2 6508.2 6753.1 6772.7 6780.8 508.4 509.8 513.9 515.1 672.1 4474.4 4572.2 4583.2 4583.7 4605.1 11765.5 12295.5 12298.1 12311.2 12327.9 11678.7 12154.3 12181.6 12200.9 12601.0 8960.5 9024.8 9092.4 9107.0 9119.4 6462.3 6578.0 6672.8 6817.1 6853.1 10615.2 10750.4 11039.9 11501.6 11534.2 11180.6 11234.9 11402.5 11403.6 11525.7 7592.2 8205.1 8304.2 8372.1 8558.4 9566.2 9568.3 9606.2 9636.4 9653.7 8327.8 8525.6 8592.2 8600.4 8681.6 8493.0 8501.6 8512.0 8593.0 8609.5 8013.8 8087.1 8090.6 8093.7 8121.5 5563.2 5680.3 5767.6 5887.3 5915.7 5733.9 5899.2 5933.9 5972.6 6298.6 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | [#4] prototype.html Cycles:180 Average:4694.77 Median:5205.00 stddev:1336.29 (25.7%) stddev-sans-first:1299.66 06:17:06 INFO - PROCESS | 6589 | Values: 351.1 354.8 362.9 365.6 370.6 5414.5 5437.7 5542.1 5616.0 5646.9 5443.3 5455.0 5467.9 5481.3 5498.3 4267.4 4299.7 4310.0 4319.0 4342.5 4093.8 4234.7 4342.5 4347.8 4351.0 4289.9 4311.3 4319.6 4324.8 4326.0 4256.0 4288.7 4297.3 4301.2 4309.8 3705.7 3820.4 3825.6 3842.6 3854.7 5242.8 5277.9 5287.0 5307.5 5307.9 4869.2 4908.7 4930.3 4951.5 4977.0 5619.4 5714.7 5750.8 5790.3 5791.9 4238.3 4247.3 4248.9 4265.7 4273.1 5990.9 6000.3 6004.5 6006.4 6011.7 4252.0 4295.5 4324.5 4341.8 4357.0 4373.6 4389.0 4395.5 4403.5 4424.1 4952.8 4960.0 4978.3 4983.3 5022.1 5563.6 5785.7 5816.7 5829.7 5835.4 1448.6 1481.5 1503.2 1521.1 1524.8 972.9 991.9 1000.7 1001.3 1003.3 5025.7 5057.4 5067.3 5093.9 5116.4 5209.0 5244.5 5257.2 5286.6 5312.5 4734.9 5032.6 5053.7 5063.3 5068.4 2796.7 2824.5 2834.8 2836.1 2843.3 5378.5 5461.7 5546.5 5549.7 5561.4 5198.8 5345.7 5364.8 5447.9 5464.4 5410.9 5420.3 5430.4 5485.2 5490.6 5183.7 5201.0 5269.8 5362.4 5371.2 4954.6 5111.9 5140.6 5141.9 5187.4 5397.2 5457.3 5457.9 5465.3 5494.8 3844.1 3985.8 4006.3 4010.7 4018.0 5319.3 5321.5 5329.9 5701.8 5793.2 5728.6 5782.7 5783.1 5819.3 5822.0 5741.0 5775.8 5789.1 5799.1 5812.6 5541.7 5662.7 5685.2 5693.5 5752.4 5772.3 5774.0 5791.9 5803.5 5823.3 5537.9 5724.6 5739.3 5742.7 5779.9 06:17:06 INFO - PROCESS | 6589 | 06:17:06 INFO - PROCESS | 6589 | [#5] yui.html Cycles:180 Average:21426.22 Median:4757.04 stddev:30409.67 (639.3%) stddev-sans-first:30455.31 06:17:06 INFO - PROCESS | 6589 | Values: 741.1 755.5 799.6 840.7 852.2 15150.2 16136.3 16510.0 16516.8 16976.2 16863.2 17390.0 17410.6 17421.9 17422.1 741.0 861.8 891.5 893.2 899.8 897.4 902.4 905.2 905.4 905.7 941.0 941.4 942.8 943.3 943.8 763.5 914.2 928.2 928.9 929.5 688.7 688.9 689.0 689.3 689.5 16443.2 16476.5 16493.3 16501.8 16535.6 14905.1 14944.2 15061.8 15103.8 15241.0 14064.6 14166.9 14199.6 14237.9 14550.9 4670.2 4723.5 4757.3 4806.7 4816.6 76386.6 77072.2 77435.6 78178.0 78410.0 11964.0 12067.8 12131.3 12187.3 12292.4 21410.3 21410.8 21672.5 21728.6 21810.5 8626.7 8715.3 8755.6 9047.7 9262.7 4990.7 5064.3 5118.2 5136.5 5200.8 3791.6 3901.6 3907.6 3908.6 3918.4 616.9 656.1 658.8 737.7 757.8 657.3 710.5 734.8 752.1 777.6 10152.6 10263.3 10459.3 10778.8 10803.0 699.0 744.9 782.7 793.5 814.1 4710.0 4733.7 4756.8 4773.1 4849.1 79040.6 80015.6 80307.6 125049.4 126963.2 76410.0 104317.0 107598.0 108733.0 112516.0 95651.0 96107.5 97544.0 98128.0 99493.0 8877.4 9016.8 9020.0 9080.9 9302.1 54961.7 56841.0 56870.1 56915.1 57156.4 74380.3 82015.0 84241.6 85222.7 86008.6 11742.0 11885.5 12449.3 12607.7 12999.7 14289.4 14364.4 14462.3 14583.1 14610.0 11809.4 11945.3 12706.4 12709.8 13856.4 13122.7 13293.4 13329.5 13425.7 13920.4 13761.9 14097.7 14125.9 14617.0 14660.2 11066.9 11097.9 11135.2 11211.1 11532.2 11300.3 11793.2 11859.5 11985.5 11988.4 06:17:06 INFO - PROCESS | 6589 | -------- Summary: end -------- 06:17:06 INFO - PROCESS | 6589 | 06:17:07 INFO - TEST-INFO | 6589: exit 0 06:17:07 INFO - TEST-OK | dromaeo_css | took 1113026ms 06:17:07 INFO - TEST-START | kraken 06:17:07 INFO - operating with platform_type : linux_ 06:17:07 INFO - Initialising browser for kraken test... 06:17:07 INFO - TEST-INFO | started process 6781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwlgyWF/profile http://localhost:33643/getInfo.html) 06:17:11 INFO - PROCESS | 6781 | __metrics Screen width/height:1600/1200 06:17:11 INFO - PROCESS | 6781 | colorDepth:24 06:17:11 INFO - PROCESS | 6781 | Browser inner width/height: 1024/697 06:17:11 INFO - PROCESS | 6781 | __metrics 06:17:12 ERROR - PROCESS | 6781 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:17:12 INFO - PROCESS | 6781 | console.error: PushService: 06:17:12 INFO - PROCESS | 6781 | stateChangeProcessEnqueue: Error transitioning state 06:17:12 INFO - PROCESS | 6781 | UnknownError 06:17:13 INFO - TEST-INFO | 6781: exit 0 06:17:13 INFO - Browser initialized. 06:17:13 INFO - Running cycle 1/1 for kraken test... 06:17:13 INFO - TEST-INFO | started process 6877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwlgyWF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:25 INFO - PROCESS | 6877 | RSS: Main: 144265216 06:17:25 INFO - PROCESS | 6877 | 06:17:27 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33643/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:28 INFO - PROCESS | 6877 | RSS: Main: 194121728 06:17:28 INFO - PROCESS | 6877 | 06:17:31 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33643/tests/kraken/driver.html?testName=audio-dft) 06:17:31 INFO - PROCESS | 6877 | RSS: Main: 193187840 06:17:31 INFO - PROCESS | 6877 | 06:17:34 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33643/tests/kraken/driver.html?testName=audio-fft) 06:17:35 INFO - PROCESS | 6877 | RSS: Main: 278609920 06:17:35 INFO - PROCESS | 6877 | 06:17:37 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33643/tests/kraken/driver.html?testName=audio-oscillator) 06:17:37 INFO - PROCESS | 6877 | RSS: Main: 187437056 06:17:37 INFO - PROCESS | 6877 | 06:17:40 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33643/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:40 INFO - PROCESS | 6877 | RSS: Main: 188325888 06:17:40 INFO - PROCESS | 6877 | 06:17:43 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33643/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:44 INFO - PROCESS | 6877 | RSS: Main: 223031296 06:17:44 INFO - PROCESS | 6877 | 06:17:47 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33643/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:48 INFO - PROCESS | 6877 | RSS: Main: 223584256 06:17:48 INFO - PROCESS | 6877 | 06:17:51 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33643/tests/kraken/driver.html?testName=json-parse-financial) 06:17:51 INFO - PROCESS | 6877 | RSS: Main: 223281152 06:17:51 INFO - PROCESS | 6877 | 06:17:53 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33643/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:53 INFO - PROCESS | 6877 | RSS: Main: 200949760 06:17:53 INFO - PROCESS | 6877 | 06:17:55 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:56 INFO - PROCESS | 6877 | RSS: Main: 202911744 06:17:56 INFO - PROCESS | 6877 | 06:17:58 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:58 INFO - PROCESS | 6877 | RSS: Main: 209866752 06:17:58 INFO - PROCESS | 6877 | 06:18:01 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:18:01 INFO - PROCESS | 6877 | RSS: Main: 216969216 06:18:01 INFO - PROCESS | 6877 | 06:18:04 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:18:05 INFO - PROCESS | 6877 | RSS: Main: 217133056 06:18:05 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | Cycle 1(1): loaded http://localhost:33643/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33643/tests/kraken/driver.html?testName=ai-astar) 06:18:06 INFO - PROCESS | 6877 | RSS: Main: 259006464 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | __start_tp_report 06:18:06 INFO - PROCESS | 6877 | _x_x_mozilla_page_load 06:18:06 INFO - PROCESS | 6877 | _x_x_mozilla_page_load_details 06:18:06 INFO - PROCESS | 6877 | |i|pagename|runs| 06:18:06 INFO - PROCESS | 6877 | |0;ai-astar;111;104;109;110;113;110;109;110;110;111 06:18:06 INFO - PROCESS | 6877 | |1;audio-beat-detection;149;127;128;171;128;125;149;123;166;131 06:18:06 INFO - PROCESS | 6877 | |2;audio-dft;162;160;161;162;161;163;160;160;162;161 06:18:06 INFO - PROCESS | 6877 | |3;audio-fft;75;75;75;126;76;76;75;75;97;74 06:18:06 INFO - PROCESS | 6877 | |4;audio-oscillator;85;84;113;85;84;85;120;85;84;121 06:18:06 INFO - PROCESS | 6877 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;117;116;116 06:18:06 INFO - PROCESS | 6877 | |6;imaging-darkroom;145;145;146;146;147;146;145;146;146;145 06:18:06 INFO - PROCESS | 6877 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;96 06:18:06 INFO - PROCESS | 6877 | |8;json-parse-financial;50;49;49;50;51;50;50;50;52;50 06:18:06 INFO - PROCESS | 6877 | |9;json-stringify-tinderbox;60;61;61;61;61;61;60;60;60;60 06:18:06 INFO - PROCESS | 6877 | |10;stanford-crypto-aes;77;76;81;76;77;78;77;77;107;76 06:18:06 INFO - PROCESS | 6877 | |11;stanford-crypto-ccm;122;118;126;116;117;127;119;127;131;138 06:18:06 INFO - PROCESS | 6877 | |12;stanford-crypto-pbkdf2;159;154;153;156;154;157;159;155;154;152 06:18:06 INFO - PROCESS | 6877 | |13;stanford-crypto-sha256-iterative;61;62;64;62;62;63;60;62;67;69 06:18:06 INFO - PROCESS | 6877 | __end_tp_report 06:18:06 INFO - PROCESS | 6877 | __start_cc_report 06:18:06 INFO - PROCESS | 6877 | _x_x_mozilla_cycle_collect,1555 06:18:06 INFO - PROCESS | 6877 | __end_cc_report 06:18:06 INFO - PROCESS | 6877 | __startTimestamp1467033486968__endTimestamp 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | ------- Summary: start ------- 06:18:06 INFO - PROCESS | 6877 | Number of tests: 14 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:2.31 (2.1%) stddev-sans-first:2.40 06:18:06 INFO - PROCESS | 6877 | Values: 111.0 104.0 109.0 110.0 113.0 110.0 109.0 110.0 110.0 111.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:140.00 stddev:17.80 (12.7%) stddev-sans-first:18.55 06:18:06 INFO - PROCESS | 6877 | Values: 149.0 127.0 128.0 171.0 128.0 125.0 149.0 123.0 166.0 131.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.03 (0.6%) stddev-sans-first:1.05 06:18:06 INFO - PROCESS | 6877 | Values: 162.0 160.0 161.0 162.0 161.0 163.0 160.0 160.0 162.0 161.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:16.80 (22.4%) stddev-sans-first:17.61 06:18:06 INFO - PROCESS | 6877 | Values: 75.0 75.0 75.0 126.0 76.0 76.0 75.0 75.0 97.0 74.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.50 stddev:16.28 (19.3%) stddev-sans-first:16.90 06:18:06 INFO - PROCESS | 6877 | Values: 85.0 84.0 113.0 85.0 84.0 85.0 120.0 85.0 84.0 121.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 06:18:06 INFO - PROCESS | 6877 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 06:18:06 INFO - PROCESS | 6877 | Values: 145.0 145.0 146.0 146.0 147.0 146.0 145.0 146.0 146.0 145.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 06:18:06 INFO - PROCESS | 6877 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.88 (1.8%) stddev-sans-first:0.93 06:18:06 INFO - PROCESS | 6877 | Values: 50.0 49.0 49.0 50.0 51.0 50.0 50.0 50.0 52.0 50.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 06:18:06 INFO - PROCESS | 6877 | Values: 60.0 61.0 61.0 61.0 61.0 61.0 60.0 60.0 60.0 60.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:77.00 stddev:9.53 (12.4%) stddev-sans-first:10.04 06:18:06 INFO - PROCESS | 6877 | Values: 77.0 76.0 81.0 76.0 77.0 78.0 77.0 77.0 107.0 76.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:126.50 stddev:7.03 (5.6%) stddev-sans-first:7.42 06:18:06 INFO - PROCESS | 6877 | Values: 122.0 118.0 126.0 116.0 117.0 127.0 119.0 127.0 131.0 138.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.30 Median:155.50 stddev:2.41 (1.5%) stddev-sans-first:2.15 06:18:06 INFO - PROCESS | 6877 | Values: 159.0 154.0 153.0 156.0 154.0 157.0 159.0 155.0 154.0 152.0 06:18:06 INFO - PROCESS | 6877 | 06:18:06 INFO - PROCESS | 6877 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:62.50 stddev:2.78 (4.4%) stddev-sans-first:2.83 06:18:06 INFO - PROCESS | 6877 | Values: 61.0 62.0 64.0 62.0 62.0 63.0 60.0 62.0 67.0 69.0 06:18:06 INFO - PROCESS | 6877 | -------- Summary: end -------- 06:18:06 INFO - PROCESS | 6877 | 06:18:07 INFO - TEST-INFO | 6877: exit 0 06:18:07 INFO - TEST-OK | kraken | took 60656ms 06:18:07 INFO - SUITE-END | took 1173s 06:18:08 INFO - Completed test suite (00:19:33) 06:18:08 INFO - javascript benchmark 06:18:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.715954143898, 3363.360961417332, 3372.6964573188407, 3406.4890266460043, 3418.8974330770084, 5520.91718624221, 5560.77756889724, 5590.972045139773, 5604.01929662309, 5609.635373700717, 5470.863228419282, 5511.862203444916, 5524.502794748471, 5525.336959564847, 5551.861203469915, 4953.306537084811, 4988.825391111302, 5007.924881126786, 5044.39467263927, 5082.186850103984, 4880.707157570557, 5004.349434573501, 5039.571636410909, 5040.16837221858, 5051.9494805051845, 5043.36957880265, 5064.215046667772, 5090.618203634724, 5099.541041306282, 5119.820806271781, 5159.71621560814, 5176.120059589879, 5182.300389447414, 5184.326037615105, 5202.8699282518055, 4874.561289483963, 4937.061958227961, 4970.0, 5073.0, 5095.159298715707, 5355.973220133875, 5403.729813509348, 5431.755570999275, 5451.890962180773, 5511.338639363262, 5953.166556682068, 5954.07711804671, 5962.25471816023, 5964.880702385972, 5971.910421343683, 5888.911666325009, 5926.555508336849, 5932.822015339503, 5950.0, 5996.43033911782, 5567.053600887859, 5586.748596313176, 5593.748281327309, 5609.186667933127, 5621.522170615503, 5884.08796636522, 6083.501986357862, 6084.113924683363, 6119.0, 6129.11127886454, 5757.481826635539, 5788.479036886701, 5833.912491312633, 5844.444777746107, 5873.353931067579, 5925.111233314951, 5952.76188952437, 5956.404359564009, 5961.344252132262, 5968.7015649217365, 5760.36635970043, 5853.765849366063, 5879.735411906475, 5886.175935369051, 5887.175795388649, 5886.941130588725, 5913.852153696192, 5950.0, 5959.195508606283, 5995.130706047598, 4548.931766023512, 4554.339620754972, 4554.362389265505, 4559.680822342404, 4572.359869618256, 4012.337964235927, 4017.2367250222364, 4047.0691740899756, 4058.9188216236103, 4100.466939297872, 5684.37471878093, 5705.429457054262, 5710.885782284414, 5713.771449141987, 5733.3406658234, 5918.8816223675285, 5920.674362910081, 5924.0, 5985.491233245136, 6028.788992385245, 5496.615236933376, 5542.418046105182, 5557.027520184032, 5558.610897237155, 5650.63270887391, 5108.055009823194, 5120.257562653469, 5124.718140502234, 5125.948740512622, 5138.8972220556125, 6168.25980882297, 6170.136180934673, 6179.412955769195, 6186.845328866814, 6191.071339299141, 5799.594028417971, 5933.02105152645, 5935.851603709942, 5968.850778730566, 5989.790357337472, 6184.350643182401, 6200.3489633589625, 6200.720967556561, 6212.47193988516, 6214.316425193316, 5888.911666325094, 5889.617174883619, 5891.469767720754, 5892.292924849045, 5924.674142922008, 5794.478496935296, 5897.233359663216, 5919.289685237799, 5931.555133364928, 5960.701964901824, 5778.335491418541, 5803.100519419497, 5817.418258174149, 5823.330317013598, 5831.037878750129, 5764.423557644202, 5810.767569297181, 5820.505257053198, 5824.970875145597, 5825.097109947965, 5532.972335138459, 5681.0910254359105, 5728.427157284238, 5740.540756739534, 5784.190213370048, 5893.823185304448, 5909.704514774158, 5935.258092738408, 5940.108983652487, 5940.910886336622, 5611.971940140436, 5783.363829978614, 5786.594938354275, 5787.39232380594, 5813.011787995966], "value": 5486.572101364398, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.1982783586487, 642.3672682407826, 643.3020173112174, 653.2879161713734, 664.8105289992353, 34121.0, 37338.62661373385, 46407.535924640746, 47320.053598928, 47485.0, 48176.518234817726, 48807.755961220144, 48809.51190488094, 48898.75550622251, 48943.51056489434, 8977.640894364235, 9127.543622818866, 9132.589033493476, 9149.405288656217, 9159.587818548149, 8473.449225800303, 10626.14990800734, 11013.28413653114, 11020.779584408307, 11329.660110196666, 11045.502952367122, 11119.833202501968, 11132.220744547885, 11137.164712646543, 11138.108951283919, 8404.915950840474, 11028.28316159451, 11107.944460277648, 11112.888871111307, 11155.330680159217, 10606.257561970666, 10673.092787113104, 10937.83593246102, 10947.890521094847, 10960.835587466194, 44886.55113448857, 45305.0, 45443.31835022477, 45479.772601136785, 45529.317060244124, 3680.1351682354893, 3801.239752049601, 3833.195029043904, 3945.171513982067, 4398.34024896265, 33333.83333083343, 33707.0, 33895.661043389744, 34076.82961585176, 34265.0, 4550.0, 4771.785269662873, 4774.665773395864, 4788.162071637449, 4859.902801943977, 353250.0, 354223.0, 355285.22357388306, 356128.0, 356561.0, 69732.3026769736, 70315.64842175858, 70353.0, 70382.2961770386, 70438.0, 40761.184776304304, 41615.58384416117, 41778.79110604488, 43341.56658433438, 73103.63448182725, 18419.72370414445, 18493.7225941611, 18555.72166417505, 18593.256269749058, 18609.162587683586, 12960.157589756636, 13490.0, 13713.245771482665, 13808.240546770025, 14036.368363423671, 22320.665190022162, 22403.55192896133, 22425.99083041267, 22431.55136897253, 22500.887495562416, 8007.357572001226, 8143.79640508992, 8152.959235203786, 8252.793680158044, 8386.70006149048, 951.6526467839327, 1059.6238335390979, 1105.8617672790901, 1124.797536443448, 1252.3675543850297, 33944.0, 34087.65912340894, 34252.828735856165, 34742.65257347394, 34771.65228347735, 1127.6560649002133, 1167.497975870384, 1200.0819373179525, 1200.7598480303975, 1208.8307636930838, 17909.373171938918, 17989.910050449664, 17996.0, 18097.0, 18234.270629174684, 546520.0, 550123.0, 552123.0, 553112.0, 554317.0, 63745.36254637487, 63766.0, 63850.36149638537, 63965.68017159885, 64502.35497645057, 1347.198416941918, 1464.2385959301105, 1466.8459811719829, 1483.1397789282191, 1490.292111247149, 32580.0, 34039.31921361609, 35147.82426087853, 35406.822965885, 35432.0, 54151.45848541622, 54347.45652543424, 54648.180277295076, 54746.0, 54763.45236547584, 414192.0, 415088.0, 415560.53318400495, 416713.0, 417867.0, 28189.859050704614, 30481.847590761903, 31624.841875790473, 31722.0, 32744.836275818467, 51041.0, 51750.223746643074, 52079.218811717095, 52124.47875521196, 52890.47109528856, 44275.55724442843, 44782.77608611936, 44886.77556612196, 45053.77473112744, 46018.76990615026, 43316.0, 43593.0, 44019.55980440155, 44514.3322850151, 44709.10581788281, 42653.933651658954, 43200.13599728051, 43413.782931085145, 43908.341374880045, 43910.0, 36906.44640330344, 39851.60148398479, 40602.1879562413, 40652.59347406488, 40857.79571102125, 28793.0, 30931.536026959162, 31019.379612408076, 31140.22149446191, 31241.37517249597], "value": 21743.835451541094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.7010653093984, 434.941282926805, 435.61012893460344, 441.9624331931786, 450.29079200259577, 18994.240230390784, 20168.69746953795, 20739.792602073976, 21308.0, 21320.680189797167, 19819.306324278652, 20004.999750012514, 20352.796472035276, 20363.185472581117, 20379.898100509476, 5788.913166302509, 6064.696765161724, 6085.939140608603, 6100.877982440348, 6104.328523862371, 4107.568705285947, 5767.971160144193, 5804.593678442489, 6015.368386319439, 6055.394460553954, 6075.878482430348, 6132.233470816148, 6134.5092392608485, 6166.4450199482035, 6173.938260617404, 4100.5899410059055, 5759.798407055754, 6056.757729690829, 6066.907956567816, 6099.298580663214, 7007.684654190522, 7380.151282602462, 7394.445416593777, 7400.074990626172, 7500.512466689648, 24355.87822060896, 25694.0, 26792.0, 27115.45769084627, 27881.442371152465, 2575.266049175995, 2595.4549544595657, 2631.907883224078, 2836.0357478457217, 3004.8046876952926, 15593.220338983005, 15816.841831581653, 15853.384640723489, 15991.52025439227, 17097.743533847002, 2136.658134698441, 2144.153059541488, 2181.7490988536365, 2210.6573481110454, 2297.1041293895396, 92433.0, 98040.99999999929, 98471.0, 100555.0, 101397.0, 17683.73474397885, 18182.36361727333, 18900.338488152847, 18962.43112706621, 19154.904225478782, 34025.48961765575, 34102.317953641286, 34407.483887741706, 34535.654643453745, 34569.135771605914, 9373.140493835655, 9738.853917191247, 9743.074407931235, 9993.150582200595, 10108.898911010796, 7977.60111994398, 8134.0, 8154.083790309272, 8209.0, 8271.2142346477, 9412.858807117898, 9434.0, 9469.479178645102, 9482.431054136636, 9621.326507144433, 3394.825837841626, 3405.3131270777367, 3479.6172421033666, 3626.6192049834917, 3680.871169509108, 966.1884017425397, 983.8475036369374, 1045.5869931377063, 1083.7561548651647, 1114.6154576671036, 19401.902990484956, 20200.09099590522, 20246.0, 20328.186872525228, 20599.794002060087, 997.805427941559, 1012.5342342522522, 1030.3457304611625, 1065.9786804263872, 1067.7044354637437, 10762.192835537366, 11168.553257869595, 11210.383428911487, 11460.828087578693, 11466.827997580043, 369000.0, 369197.0, 374532.0, 482292.0, 504868.0, 90387.54806225927, 92589.5370523143, 96048.0, 97112.51443742737, 97612.51193743986, 1075.0485820049312, 1089.6131873185063, 1108.83921831334, 1113.6993011886746, 1122.38829837737, 18181.818181818013, 18303.26786928508, 18420.815791842448, 18434.72347914809, 18674.25302987865, 40050.0, 40063.0, 41167.1766564673, 42064.369034463896, 42750.0, 59358.10962835648, 60716.0, 60945.69527152336, 61014.084788729095, 61507.6924615392, 7907.48601340911, 8077.1115177329275, 8323.916760832557, 8364.121767214356, 9058.230050445522, 9958.800823983625, 13858.029937904654, 13941.960442865733, 13944.930275348559, 14390.568282951528, 6110.26676798787, 9618.326717130014, 11098.167637427065, 11824.23142495735, 12350.0, 5810.360860305278, 9735.490998895182, 11025.117990560897, 11348.943255283672, 11501.769964600828, 11724.58963936258, 12045.0, 12555.121141520005, 12619.558315458915, 12847.614571562868, 7780.221977802401, 8247.340212783083, 8389.0, 8924.509151996703, 9393.718188454357, 9449.480278584744, 11446.198766086294, 11914.463849126636, 11990.280583165038, 12446.751064978469], "value": 10414.511274076463, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.4786053090625, 1595.752658337958, 1637.7870876786017, 1859.4049904030708, 1987.7018447232913, 9112.863307050391, 9499.952500237498, 9582.329236953416, 9861.60553577856, 9888.0, 9545.952270238638, 9775.608975640966, 9776.169025632811, 9779.706608801729, 9798.265130115235, 2515.811314151437, 2535.2267558394683, 2537.5178716043984, 2550.7194208637034, 2554.642350070982, 2485.2047344849657, 2540.161746623619, 2547.121243171103, 2547.1339744486872, 2550.311415917701, 2525.103588226181, 2525.5201511712808, 2551.336652470362, 2557.488502299538, 2557.5907854743245, 2443.523512914991, 2461.9876900615473, 2473.9134130305442, 2474.6040633498733, 2477.578811602032, 2354.09367393553, 2358.952820943589, 2362.444825466014, 2370.573296806574, 2445.2786428003783, 8969.416987895765, 9213.401128926665, 9730.513474326255, 9746.46394448305, 9779.266555008402, 5431.375391829951, 5624.465675760796, 5626.746796394172, 5627.521660658849, 5630.746616402272, 3641.47198656196, 3750.362438385481, 3772.9811350943346, 3887.4168874668744, 3889.649931506178, 1251.555697727303, 1274.222724138275, 1301.3168086754436, 1309.5940258519795, 1323.543377534749, 13826.861731382758, 14020.579382618538, 14078.507252246121, 14086.788698169536, 14105.717885642229, 5058.291839142449, 5144.61415393847, 5172.844814655567, 5173.663711858717, 5259.132243179909, 11657.125715571367, 11709.0, 11768.35274059935, 11794.705132371588, 11796.88203117975, 5645.5201307888465, 5682.516986056149, 5707.229524014205, 5716.171155182558, 5728.541716662657, 4590.219662657356, 4632.2356811126465, 5120.590352771769, 5126.743662816919, 5188.377394712583, 4280.5505421930875, 4292.313229883203, 4307.375430562551, 4354.194474022316, 4380.342948557742, 1357.4931825673495, 1375.690469644322, 1380.4547203854422, 1381.3162484570203, 1396.1692792788274, 863.0299187007977, 951.5955718819474, 1061.7239517725448, 1077.7628921637227, 1084.815581351172, 6411.198600174978, 6508.219013718384, 6753.08833307507, 6772.729090836311, 6780.796576102724, 508.351851389474, 509.83940058881336, 513.8792383789806, 515.0960065085751, 672.0691841799144, 4474.351219073216, 4572.245579479415, 4583.175028494837, 4583.679142459996, 4605.148047611203, 11765.470553825098, 12295.508179672892, 12298.139130260795, 12311.19977201479, 12327.876721232673, 11678.70803229926, 12154.270743755404, 12181.573644922562, 12200.938995305145, 12601.0, 8960.462372257685, 9024.819503609891, 9092.363534552518, 9106.95446522763, 9119.407238529342, 6462.321456247027, 6578.0, 6672.766453174116, 6817.079694241413, 6853.143357080365, 10615.1507879371, 10750.354978701303, 11039.889601103887, 11501.597444089752, 11534.1926065178, 11180.608678696206, 11234.887651123385, 11402.543898243977, 11403.600873969372, 11525.711857203636, 7592.202818703957, 8205.056418511947, 8304.211099945496, 8372.120927302545, 8558.358123140664, 9566.234701223746, 9568.282378821756, 9606.231501480004, 9636.42181469114, 9653.662121825702, 8327.833443331221, 8525.573721314033, 8592.183742544448, 8600.440971336842, 8681.565921703763, 8493.0, 8501.574921253788, 8512.02111757156, 8592.957035214784, 8609.526476043628, 8013.8397232056195, 8087.1104178539135, 8090.595470226347, 8093.676252949816, 8121.4721043132, 5563.193336966036, 5680.346760122638, 5767.5674324425, 5887.322957859729, 5915.70421478933, 5733.942660573341, 5899.174115623902, 5933.881322373615, 5972.552058595709, 6298.622082675054], "value": 5021.350259708455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.1397077160955, 354.77649081078926, 362.8802495176592, 365.63071297989023, 370.5738400839033, 5414.512693857552, 5437.673739575628, 5542.140968149938, 5615.971920140399, 5646.8588285292835, 5443.346798384199, 5455.0, 5467.9453205468035, 5481.287432633752, 5498.285222921025, 4267.423897773791, 4299.721147791675, 4309.956900430987, 4318.956810431902, 4342.54403287655, 4093.751405821238, 4234.724742891703, 4342.457192850894, 4347.804348804311, 4351.0, 4289.935650965238, 4311.33174357975, 4319.611234988851, 4324.805383757738, 4326.0, 4255.978720106411, 4288.678349123828, 4297.312430011199, 4301.204277208695, 4309.84915527955, 3705.6664900159117, 3820.4078367853026, 3825.5600605930395, 3842.5965273646143, 3854.7494412863352, 5242.816501422432, 5277.894442111136, 5287.0, 5307.495787900143, 5307.946920530784, 4869.172240719086, 4908.656394052417, 4930.260460930853, 4951.53929590768, 4976.950230497685, 5619.409961953977, 5714.685692286923, 5750.769969201227, 5790.305163380379, 5791.855203619901, 4238.321868501025, 4247.320428731388, 4248.936265956013, 4265.680073994432, 4273.14118358515, 5990.8502287442725, 6000.309964354112, 6004.519638428959, 6006.399360064046, 6011.699415029275, 4251.9787401062795, 4295.453636690789, 4324.545922678137, 4341.82632694695, 4356.976110614003, 4373.606375426227, 4388.956110438919, 4395.472543294825, 4403.471583409947, 4424.137293227854, 4952.801887924515, 4960.032793605213, 4978.303037574742, 4983.327250821165, 5022.146235140035, 5563.582731295088, 5785.739641716049, 5816.709164541756, 5829.650220986754, 5835.35811060783, 1448.608875603581, 1481.5036962617644, 1503.225838693074, 1521.1253529220742, 1524.7941527893593, 972.8929817720045, 991.861139440479, 1000.6997900629782, 1001.2740762946805, 1003.3177439341318, 5025.748712564357, 5057.393112826484, 5067.290579318898, 5093.8726531836255, 5116.386033675983, 5208.9739551302755, 5244.505315984926, 5257.239949026792, 5286.5506431952945, 5312.5484333832055, 4734.928976065362, 5032.572231360302, 5053.69677819332, 5063.3417655704525, 5068.41713202979, 2796.7063458336584, 2824.491591513547, 2834.8313540141808, 2836.134978831476, 2843.2607522044004, 5378.462153784591, 5461.672299662033, 5546.505701408087, 5549.667019978814, 5561.360443548962, 5198.818041368534, 5345.679259244458, 5364.785408583691, 5447.945520544744, 5464.371597266365, 5410.891782164256, 5420.268263784417, 5430.42980487051, 5485.232067510552, 5490.6431081979545, 5183.740812959261, 5201.027534560513, 5269.841904742864, 5362.3833259174135, 5371.248025276386, 4954.5788607968725, 5111.897762044812, 5140.586338756872, 5141.897162056813, 5187.377514698261, 5397.244385785914, 5457.290552228185, 5457.908418316432, 5465.344158700981, 5494.780208791603, 3844.0966372902903, 3985.820638071352, 4006.338954072662, 4010.6791456682813, 4017.995501124719, 5319.335083114611, 5321.494458026481, 5329.893402131858, 5701.831124619526, 5793.246877906014, 5728.570357223141, 5782.739776710142, 5783.132530120515, 5819.272590926134, 5821.970890145522, 5741.0, 5775.826725198251, 5789.102689083199, 5799.130130480462, 5812.604974805931, 5541.667499949855, 5662.688552129508, 5685.232493613309, 5693.4875861172695, 5752.446839353267, 5772.278465191851, 5773.9895518284975, 5791.884162316815, 5803.506701930383, 5823.272090988627, 5537.944620553743, 5724.627899186539, 5739.282589676291, 5742.684152371659, 5779.855503612275], "value": 4278.881885464814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.0588552538273, 755.4824944912732, 799.6201804143033, 840.7477756672997, 852.1904191018532, 15150.16674082925, 16136.273867675953, 16510.0, 16516.83483165168, 16976.15119244036, 16863.156842157903, 17390.043547604877, 17410.56473588161, 17421.91289043556, 17422.128893555335, 740.988885166723, 861.8405594964952, 891.505214605895, 893.2228960804109, 899.7660608241872, 897.3673560140086, 902.4044130873625, 905.1627244798568, 905.41148253635, 905.6965916417975, 941.0354386753598, 941.3645789092369, 942.8114377124568, 943.3208090175098, 943.79708362702, 763.4770182425029, 914.2457472585111, 928.215657769184, 928.8792456980617, 929.5073610986221, 688.686647575352, 688.9242183359827, 688.9931100689029, 689.290031267795, 689.4794430205213, 16443.177841108016, 16476.505704828873, 16493.257803398876, 16501.83498165027, 16535.586610334838, 14905.105693658415, 14944.178070206135, 15061.774073388908, 15103.848961510354, 15241.0, 14064.648383790487, 14166.92916535421, 14199.645008874757, 14237.857621423756, 14550.927245363811, 4670.182718024332, 4723.527647235283, 4757.26262429324, 4806.711597304173, 4816.56650901417, 76386.61806690987, 77072.22927770707, 77435.61282193553, 78178.0, 78410.0, 11964.0, 12067.758644827054, 12131.332776697367, 12187.268763874195, 12292.446839892047, 21410.25064122748, 21410.78589214119, 21672.458188545414, 21728.565428691338, 21810.454738631342, 8626.741197764077, 8715.34634902372, 8755.562221889008, 9047.728568142966, 9262.72211833646, 4990.675606085593, 5064.34163558735, 5118.155504341742, 5136.5377116059735, 5200.76596553156, 3791.6018818024263, 3901.5708272090046, 3907.6092390760696, 3908.6286802753693, 3918.4318273850704, 616.8673735146965, 656.0880376276979, 658.7957733102753, 737.71229220604, 757.8484303139394, 657.3163909534127, 710.4848984486206, 734.8273155808381, 752.1312883619319, 777.6306254529053, 10152.593896244067, 10263.332883362558, 10459.320144190604, 10778.78442431147, 10803.0, 698.9615571143535, 744.8919906613511, 782.7416952405692, 793.4604468961046, 814.1044850664222, 4710.0, 4733.715977041388, 4756.8097276108565, 4773.1408346497965, 4849.078675051729, 79040.60479697565, 80015.59992200002, 80307.59846200731, 125049.37475312747, 126963.22946825063, 76410.0, 104317.0, 107598.0, 108733.0, 112516.0, 95651.0, 96107.51946240364, 97544.0, 98128.0, 99493.0, 8877.422967507091, 9016.819663606822, 9020.0, 9080.863787043332, 9302.069793020644, 54961.725191373785, 56841.0, 56870.146947796646, 56915.14627280511, 57156.42843571511, 74380.25619743734, 82015.0, 84241.57879210565, 85222.72259778364, 86008.56995714981, 11742.0, 11885.524579016743, 12449.315287658903, 12607.747845043232, 12999.675008124872, 14289.35697893618, 14364.425422983382, 14462.27688615544, 14583.08338332359, 14610.0, 11809.409529523662, 11945.283283003047, 12706.428210730355, 12709.8093528599, 13856.376463058985, 13122.737545249232, 13293.401796918968, 13329.533466328632, 13425.664358390728, 13920.443182272596, 13761.862381376459, 14097.718045639234, 14125.858741412865, 14617.049891757002, 14660.193689347188, 11066.889331106908, 11097.889021110008, 11135.220534562503, 11211.103111750877, 11532.192746507999, 11300.265482743594, 11793.233439826385, 11859.525618975144, 11985.460654270753, 11988.400579971141], "value": 7961.569489959031, "unit": "score"}], "name": "dromaeo_css", "value": 7725.239430259969, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 109.0, 110.0, 113.0, 110.0, 109.0, 110.0, 110.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 127.0, 128.0, 171.0, 128.0, 125.0, 149.0, 123.0, 166.0, 131.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 162.0, 161.0, 163.0, 160.0, 160.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 126.0, 76.0, 76.0, 75.0, 75.0, 97.0, 74.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 113.0, 85.0, 84.0, 85.0, 120.0, 85.0, 84.0, 121.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 50.0, 51.0, 50.0, 50.0, 50.0, 52.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 81.0, 76.0, 77.0, 78.0, 77.0, 77.0, 107.0, 76.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 118.0, 126.0, 116.0, 117.0, 127.0, 119.0, 127.0, 131.0, 138.0], "value": 124.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 153.0, 156.0, 154.0, 157.0, 159.0, 155.0, 154.0, 152.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 64.0, 62.0, 62.0, 63.0, 60.0, 62.0, 67.0, 69.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1478.3, "alertThreshold": 2.0}]} 06:18:08 INFO - Return code: 0 06:18:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:18:08 ERROR - # TBPL SUCCESS # 06:18:08 INFO - Running post-action listener: _resource_record_post_action 06:18:08 INFO - ##### 06:18:08 INFO - ##### Finished run-tests step (success) 06:18:08 INFO - ##### 06:18:08 INFO - Running post-run listener: _resource_record_post_run 06:18:08 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504365056; Read time: 96; Write time: 479696 06:18:08 INFO - TinderboxPrint: CPU usage
13.2% 06:18:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 96 06:18:08 INFO - TinderboxPrint: I/O write bytes / time
504,365,056 / 479,696 06:18:08 INFO - TinderboxPrint: CPU idle
8,252.6 (86.8%) 06:18:08 INFO - TinderboxPrint: CPU user
1,203.9 (12.7%) 06:18:08 INFO - TinderboxPrint: Swap in / out
0 / 0 06:18:08 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252153856; Read time: 0; Write time: 274048 06:18:08 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251539456; Read time: 96; Write time: 205580 06:18:08 INFO - Running post-run listener: _upload_blobber_files 06:18:08 INFO - Blob upload gear active. 06:18:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:18:08 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 06:18:08 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'] 06:18:08 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 06:18:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:18:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:18:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:18:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:10 INFO - (blobuploader) - INFO - Done attempting. 06:18:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:18:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:18:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:13 INFO - (blobuploader) - INFO - Done attempting. 06:18:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:18:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:14 INFO - (blobuploader) - INFO - Done attempting. 06:18:14 INFO - (blobuploader) - INFO - Iteration through files over. 06:18:14 INFO - Return code: 0 06:18:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:18:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:18:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba5b1dee255241e741c2c4e325f932ea80fc6b5e44b1f5884ea8bf26d72bb456bf5c49e5a77405c2c0486eebefbb39a06d827946b46b6fd9e21dd868a677b3fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe994dd97096923009d36485b9191d9ce63414bb43579a7d6adac2c396d333964aedd5d87d068b8e1090ba24c1bf92a0be4434ade544121ad195ec2d35153fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acdd1424fc5a575019797229a71ec93a525f32acd969b0294637ef0f58648ca266ba289438777dee38cac391455f44e3d3bc3f38def4592c499472de243930b1"} 06:18:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:18:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:18:14 INFO - Contents: 06:18:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba5b1dee255241e741c2c4e325f932ea80fc6b5e44b1f5884ea8bf26d72bb456bf5c49e5a77405c2c0486eebefbb39a06d827946b46b6fd9e21dd868a677b3fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe994dd97096923009d36485b9191d9ce63414bb43579a7d6adac2c396d333964aedd5d87d068b8e1090ba24c1bf92a0be4434ade544121ad195ec2d35153fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acdd1424fc5a575019797229a71ec93a525f32acd969b0294637ef0f58648ca266ba289438777dee38cac391455f44e3d3bc3f38def4592c499472de243930b1"} 06:18:14 INFO - Running post-run listener: copy_logs_to_upload_dir 06:18:14 INFO - Copying logs to upload dir... 06:18:14 INFO - mkdir: /builds/slave/test/build/upload/logs 06:18:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.023868 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-06-27 06:18:14.216741) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 06:18:14.219962) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447 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/ba5b1dee255241e741c2c4e325f932ea80fc6b5e44b1f5884ea8bf26d72bb456bf5c49e5a77405c2c0486eebefbb39a06d827946b46b6fd9e21dd868a677b3fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe994dd97096923009d36485b9191d9ce63414bb43579a7d6adac2c396d333964aedd5d87d068b8e1090ba24c1bf92a0be4434ade544121ad195ec2d35153fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acdd1424fc5a575019797229a71ec93a525f32acd969b0294637ef0f58648ca266ba289438777dee38cac391455f44e3d3bc3f38def4592c499472de243930b1"} build_url:https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/DBiGjFYkRpWvoIGPxASbvA/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/ba5b1dee255241e741c2c4e325f932ea80fc6b5e44b1f5884ea8bf26d72bb456bf5c49e5a77405c2c0486eebefbb39a06d827946b46b6fd9e21dd868a677b3fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe994dd97096923009d36485b9191d9ce63414bb43579a7d6adac2c396d333964aedd5d87d068b8e1090ba24c1bf92a0be4434ade544121ad195ec2d35153fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acdd1424fc5a575019797229a71ec93a525f32acd969b0294637ef0f58648ca266ba289438777dee38cac391455f44e3d3bc3f38def4592c499472de243930b1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 06:18:14.250043) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 06:18:14.250774) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1467032259.610567-1288826447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 06:18:14.331677) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 06:18:14.332031) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 06:18:14.332361) ========= ========= Total master_lag: 0.17 =========