builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1469872860.46 results: success (0) buildid: 20160729182925 builduid: 2dd017203036455b9f594a3790630745 revision: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.456437) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.456926) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.457217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.523281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.523556) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 03:01:00-- 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% 222M=0s 2016-07-30 03:01:00 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129867 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.746601) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.747005) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022939 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.831718) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.832030) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8847dd9e9a2da7a4ad074ab97e085295e50746c3 --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 8847dd9e9a2da7a4ad074ab97e085295e50746c3 --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=03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 03:01:01,214 truncating revision to first 12 chars 2016-07-30 03:01:01,214 Setting DEBUG logging. 2016-07-30 03:01:01,214 attempt 1/10 2016-07-30 03:01:01,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8847dd9e9a2d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 03:01:01,529 unpacking tar archive at: mozilla-aurora-8847dd9e9a2d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.682873 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.540122) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.543560) ========= script_repo_revision: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.544038) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.544379) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.584668) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-30 03:01:01.584988) ========= /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=03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:01:02 INFO - MultiFileLogger online at 20160730 03:01:02 in /builds/slave/test 03:01:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:01:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:01:02 INFO - {'append_to_log': False, 03:01:02 INFO - 'base_work_dir': '/builds/slave/test', 03:01:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:01:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:01:02 INFO - 'branch': 'Mozilla-Aurora', 03:01:02 INFO - 'buildbot_json_path': 'buildprops.json', 03:01:02 INFO - 'config_files': ('talos/linux_config.py',), 03:01:02 INFO - 'default_actions': ('clobber', 03:01:02 INFO - 'read-buildbot-config', 03:01:02 INFO - 'download-and-extract', 03:01:02 INFO - 'populate-webroot', 03:01:02 INFO - 'create-virtualenv', 03:01:02 INFO - 'install', 03:01:02 INFO - 'run-tests'), 03:01:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:01:02 INFO - 'download_minidump_stackwalk': True, 03:01:02 INFO - 'download_symbols': 'ondemand', 03:01:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:01:02 INFO - 'tooltool.py': '/tools/tooltool.py', 03:01:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:01:02 INFO - '/tools/misc-python/virtualenv.py')}, 03:01:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:01:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:01:02 INFO - 'installer_path': 'installer.exe', 03:01:02 INFO - 'log_level': 'info', 03:01:02 INFO - 'log_name': 'talos', 03:01:02 INFO - 'log_to_console': True, 03:01:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:01:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:01:02 INFO - 'opt_config_files': (), 03:01:02 INFO - 'pip_index': False, 03:01:02 INFO - 'sps_profile': False, 03:01:02 INFO - 'sps_profile_interval': 0, 03:01:02 INFO - 'suite': 'dromaeojs', 03:01:02 INFO - 'system_bits': '32', 03:01:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:01:02 INFO - 'title': 'talos-linux64-ix-047', 03:01:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:01:02 INFO - 'use_talos_json': True, 03:01:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:01:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:01:02 INFO - 'work_dir': 'build'} 03:01:02 INFO - ##### 03:01:02 INFO - ##### Running clobber step. 03:01:02 INFO - ##### 03:01:02 INFO - Running pre-action listener: _resource_record_pre_action 03:01:02 INFO - Running main action method: clobber 03:01:02 INFO - rmtree: /builds/slave/test/build 03:01:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:01:02 INFO - Running post-action listener: _resource_record_post_action 03:01:02 INFO - ##### 03:01:02 INFO - ##### Finished clobber step (success) 03:01:02 INFO - ##### 03:01:02 INFO - ##### 03:01:02 INFO - ##### Running read-buildbot-config step. 03:01:02 INFO - ##### 03:01:02 INFO - Running pre-action listener: _resource_record_pre_action 03:01:02 INFO - Running main action method: read_buildbot_config 03:01:02 INFO - Using buildbot properties: 03:01:02 INFO - { 03:01:02 INFO - "project": "", 03:01:02 INFO - "product": "firefox", 03:01:02 INFO - "script_repo_revision": "production", 03:01:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:01:02 INFO - "repository": "", 03:01:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:01:02 INFO - "buildid": "20160729182925", 03:01:02 INFO - "pgo_build": "True", 03:01:02 INFO - "basedir": "/builds/slave/test", 03:01:02 INFO - "buildnumber": 396, 03:01:02 INFO - "slavename": "talos-linux64-ix-047", 03:01:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:01:02 INFO - "platform": "ubuntu64_hw", 03:01:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:01:02 INFO - "branch": "mozilla-aurora", 03:01:02 INFO - "repo_path": "releases/mozilla-aurora", 03:01:02 INFO - "revision": "8847dd9e9a2da7a4ad074ab97e085295e50746c3", 03:01:02 INFO - "stage_platform": "linux64-pgo", 03:01:02 INFO - "builduid": "2dd017203036455b9f594a3790630745", 03:01:02 INFO - "slavebuilddir": "test" 03:01:02 INFO - } 03:01:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:01:02 INFO - Running post-action listener: _resource_record_post_action 03:01:02 INFO - ##### 03:01:02 INFO - ##### Finished read-buildbot-config step (success) 03:01:02 INFO - ##### 03:01:02 INFO - ##### 03:01:02 INFO - ##### Running download-and-extract step. 03:01:02 INFO - ##### 03:01:02 INFO - Running pre-action listener: _resource_record_pre_action 03:01:02 INFO - Running main action method: download_and_extract 03:01:02 INFO - mkdir: /builds/slave/test/build/tests 03:01: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')]} 03:01:02 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:01:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:01:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:01:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:01:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:01:06 INFO - Downloaded 1361 bytes. 03:01:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:01:06 INFO - Using the following test package requirements: 03:01:06 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:01:06 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:01:06 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:01:06 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'jsshell-linux-x86_64.zip'], 03:01:06 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:01:06 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:01:06 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:01:06 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:01:06 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:01:06 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:01:06 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:01: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')]} 03:01:06 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:01:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:01:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:01:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:01:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:01:07 INFO - Downloaded 24495819 bytes. 03:01:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:01:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:01:08 INFO - Return code: 0 03:01:08 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:08 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:01:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:01:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:01:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:01:10 INFO - Downloaded 24495819 bytes. 03:01:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:01:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:01:12 INFO - Return code: 0 03:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:12 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:01:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:01:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:01:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:01:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:01:13 INFO - Downloaded 10833369 bytes. 03:01:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:01:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:01:13 INFO - Return code: 0 03:01: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')]} 03:01:13 INFO - https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:01:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:01:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:01:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:01:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:01:14 INFO - Downloaded 63641382 bytes. 03:01:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:01:14 INFO - mkdir: /builds/slave/test/properties 03:01:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:01:14 INFO - Writing to file /builds/slave/test/properties/build_url 03:01:14 INFO - Contents: 03:01:14 INFO - build_url:https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:01:15 INFO - Running post-action listener: _resource_record_post_action 03:01:15 INFO - Running post-action listener: set_extra_try_arguments 03:01:15 INFO - ##### 03:01:15 INFO - ##### Finished download-and-extract step (success) 03:01:15 INFO - ##### 03:01:15 INFO - ##### 03:01:15 INFO - ##### Running populate-webroot step. 03:01:15 INFO - ##### 03:01:15 INFO - Running pre-action listener: _resource_record_pre_action 03:01:15 INFO - Running main action method: populate_webroot 03:01:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:01:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:01:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:01:15 INFO - '--tppagecycles', 03:01:15 INFO - '7'], 03:01:15 INFO - 'tests': ['tsvgm']}}, 03:01:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:01:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:01:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:01:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:01:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['damp', 'tps']}, 03:01:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'tests': ['damp', 'tps']}, 03:01:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['dromaeo_dom']}, 03:01:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:01:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other-e10s': {'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tsvgx', 03:01:15 INFO - 'tsvgr_opacity', 03:01:15 INFO - 'tart', 03:01:15 INFO - 'tscrollx', 03:01:15 INFO - 'cart']}, 03:01:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:01:15 INFO - 'tsvgr_opacity', 03:01:15 INFO - 'tart', 03:01:15 INFO - 'tscrollx', 03:01:15 INFO - 'cart']}, 03:01:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tp5o']}, 03:01:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'tests': ['tp5o']}, 03:01:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s', 03:01:15 INFO - '--xperf_path', 03:01:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:15 INFO - 'tests': ['tp5n']}, 03:01:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--xperf_path', 03:01:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:15 INFO - 'tests': ['tp5n']}}, 03:01:15 INFO - 'talos.zip': {'path': '', 03:01:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:01:15 INFO - Running post-action listener: _resource_record_post_action 03:01:15 INFO - ##### 03:01:15 INFO - ##### Finished populate-webroot step (success) 03:01:15 INFO - ##### 03:01:15 INFO - ##### 03:01:15 INFO - ##### Running create-virtualenv step. 03:01:15 INFO - ##### 03:01:15 INFO - Running pre-action listener: _resource_record_pre_action 03:01:15 INFO - Running main action method: create_virtualenv 03:01:15 INFO - Creating virtualenv /builds/slave/test/build/venv 03:01:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:01:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:01:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:01:15 INFO - Using real prefix '/usr' 03:01:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:01:16 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:18 INFO - Installing pip.................done. 03:01:18 INFO - Return code: 0 03:01:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:18 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:01:18 INFO - Using env: {'DISPLAY': ':0', 03:01:18 INFO - 'HOME': '/home/cltbld', 03:01:18 INFO - 'LANG': 'en_US.UTF-8', 03:01:18 INFO - 'LANGUAGE': 'en_US:en', 03:01:18 INFO - 'LOGNAME': 'cltbld', 03:01:18 INFO - 'MAIL': '/var/mail/cltbld', 03:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:18 INFO - 'MOZ_NO_REMOTE': '1', 03:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:18 INFO - 'NO_EM_RESTART': '1', 03:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:18 INFO - 'PWD': '/builds/slave/test', 03:01:18 INFO - 'SHELL': '/bin/bash', 03:01:18 INFO - 'SHLVL': '1', 03:01:18 INFO - 'TERM': 'linux', 03:01:18 INFO - 'TMOUT': '86400', 03:01:18 INFO - 'USER': 'cltbld', 03:01:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:18 INFO - '_': '/tools/buildbot/bin/python'} 03:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:01:18 INFO - Cleaning up... 03:01:18 INFO - Return code: 0 03:01:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:18 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:01:18 INFO - Using env: {'DISPLAY': ':0', 03:01:18 INFO - 'HOME': '/home/cltbld', 03:01:18 INFO - 'LANG': 'en_US.UTF-8', 03:01:18 INFO - 'LANGUAGE': 'en_US:en', 03:01:18 INFO - 'LOGNAME': 'cltbld', 03:01:18 INFO - 'MAIL': '/var/mail/cltbld', 03:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:18 INFO - 'MOZ_NO_REMOTE': '1', 03:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:18 INFO - 'NO_EM_RESTART': '1', 03:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:18 INFO - 'PWD': '/builds/slave/test', 03:01:18 INFO - 'SHELL': '/bin/bash', 03:01:18 INFO - 'SHLVL': '1', 03:01:18 INFO - 'TERM': 'linux', 03:01:18 INFO - 'TMOUT': '86400', 03:01:18 INFO - 'USER': 'cltbld', 03:01:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:18 INFO - '_': '/tools/buildbot/bin/python'} 03:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:18 INFO - Downloading/unpacking psutil>=3.1.1 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:20 INFO - Installing collected packages: psutil 03:01:20 INFO - Running setup.py install for psutil 03:01:20 INFO - building 'psutil._psutil_linux' extension 03:01:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:01:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:01:20 INFO - building 'psutil._psutil_posix' extension 03:01:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:01:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:01:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:21 INFO - Successfully installed psutil 03:01:21 INFO - Cleaning up... 03:01:21 INFO - Return code: 0 03:01:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:01:21 INFO - Using env: {'DISPLAY': ':0', 03:01:21 INFO - 'HOME': '/home/cltbld', 03:01:21 INFO - 'LANG': 'en_US.UTF-8', 03:01:21 INFO - 'LANGUAGE': 'en_US:en', 03:01:21 INFO - 'LOGNAME': 'cltbld', 03:01:21 INFO - 'MAIL': '/var/mail/cltbld', 03:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:21 INFO - 'MOZ_NO_REMOTE': '1', 03:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:21 INFO - 'NO_EM_RESTART': '1', 03:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:21 INFO - 'PWD': '/builds/slave/test', 03:01:21 INFO - 'SHELL': '/bin/bash', 03:01:21 INFO - 'SHLVL': '1', 03:01:21 INFO - 'TERM': 'linux', 03:01:21 INFO - 'TMOUT': '86400', 03:01:21 INFO - 'USER': 'cltbld', 03:01:21 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:21 INFO - '_': '/tools/buildbot/bin/python'} 03:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:23 INFO - Installing collected packages: mozsystemmonitor 03:01:23 INFO - Running setup.py install for mozsystemmonitor 03:01:23 INFO - Successfully installed mozsystemmonitor 03:01:23 INFO - Cleaning up... 03:01:23 INFO - Return code: 0 03:01:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:23 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:01:23 INFO - Using env: {'DISPLAY': ':0', 03:01:23 INFO - 'HOME': '/home/cltbld', 03:01:23 INFO - 'LANG': 'en_US.UTF-8', 03:01:23 INFO - 'LANGUAGE': 'en_US:en', 03:01:23 INFO - 'LOGNAME': 'cltbld', 03:01:23 INFO - 'MAIL': '/var/mail/cltbld', 03:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:23 INFO - 'MOZ_NO_REMOTE': '1', 03:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:23 INFO - 'NO_EM_RESTART': '1', 03:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:23 INFO - 'PWD': '/builds/slave/test', 03:01:23 INFO - 'SHELL': '/bin/bash', 03:01:23 INFO - 'SHLVL': '1', 03:01:23 INFO - 'TERM': 'linux', 03:01:23 INFO - 'TMOUT': '86400', 03:01:23 INFO - 'USER': 'cltbld', 03:01:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:23 INFO - '_': '/tools/buildbot/bin/python'} 03:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:23 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:25 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:25 INFO - warning: no files found matching '*.txt' 03:01:25 INFO - no previously-included directories found matching 'build' 03:01:25 INFO - no previously-included directories found matching 'dist' 03:01:25 INFO - no previously-included directories found matching '.git*' 03:01:25 INFO - Installing collected packages: jsonschema, functools32 03:01:25 INFO - Running setup.py install for functools32 03:01:25 INFO - warning: no files found matching '*.txt' 03:01:25 INFO - no previously-included directories found matching 'build' 03:01:25 INFO - no previously-included directories found matching 'dist' 03:01:25 INFO - no previously-included directories found matching '.git*' 03:01:25 INFO - Successfully installed jsonschema functools32 03:01:25 INFO - Cleaning up... 03:01:26 INFO - Return code: 0 03:01:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:26 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:01:26 INFO - Using env: {'DISPLAY': ':0', 03:01:26 INFO - 'HOME': '/home/cltbld', 03:01:26 INFO - 'LANG': 'en_US.UTF-8', 03:01:26 INFO - 'LANGUAGE': 'en_US:en', 03:01:26 INFO - 'LOGNAME': 'cltbld', 03:01:26 INFO - 'MAIL': '/var/mail/cltbld', 03:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:26 INFO - 'MOZ_NO_REMOTE': '1', 03:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:26 INFO - 'NO_EM_RESTART': '1', 03:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:26 INFO - 'PWD': '/builds/slave/test', 03:01:26 INFO - 'SHELL': '/bin/bash', 03:01:26 INFO - 'SHLVL': '1', 03:01:26 INFO - 'TERM': 'linux', 03:01:26 INFO - 'TMOUT': '86400', 03:01:26 INFO - 'USER': 'cltbld', 03:01:26 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:26 INFO - '_': '/tools/buildbot/bin/python'} 03:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:26 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:28 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:28 INFO - Downloading docopt-0.6.1.tar.gz 03:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:28 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:28 INFO - Running setup.py install for blobuploader 03:01:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:29 INFO - Running setup.py install for requests 03:01:29 INFO - Running setup.py install for docopt 03:01:29 INFO - Successfully installed blobuploader requests docopt 03:01:29 INFO - Cleaning up... 03:01:29 INFO - Return code: 0 03:01:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:29 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:29 INFO - Using env: {'DISPLAY': ':0', 03:01:29 INFO - 'HOME': '/home/cltbld', 03:01:29 INFO - 'LANG': 'en_US.UTF-8', 03:01:29 INFO - 'LANGUAGE': 'en_US:en', 03:01:29 INFO - 'LOGNAME': 'cltbld', 03:01:29 INFO - 'MAIL': '/var/mail/cltbld', 03:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:29 INFO - 'MOZ_NO_REMOTE': '1', 03:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:29 INFO - 'NO_EM_RESTART': '1', 03:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:29 INFO - 'PWD': '/builds/slave/test', 03:01:29 INFO - 'SHELL': '/bin/bash', 03:01:29 INFO - 'SHLVL': '1', 03:01:29 INFO - 'TERM': 'linux', 03:01:29 INFO - 'TMOUT': '86400', 03:01:29 INFO - 'USER': 'cltbld', 03:01:29 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:29 INFO - '_': '/tools/buildbot/bin/python'} 03:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:29 INFO - Running setup.py (path:/tmp/pip-NkPjbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:29 INFO - Running setup.py (path:/tmp/pip-Anh3Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:29 INFO - Running setup.py (path:/tmp/pip-pZL0UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:29 INFO - Running setup.py (path:/tmp/pip-JRUZ8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:30 INFO - Running setup.py (path:/tmp/pip-fVoD5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:30 INFO - Running setup.py (path:/tmp/pip-JQ6zbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:30 INFO - Running setup.py (path:/tmp/pip-ajkYdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:30 INFO - Running setup.py (path:/tmp/pip-xX9Hrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:30 INFO - Running setup.py (path:/tmp/pip-EG7I9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:30 INFO - Running setup.py (path:/tmp/pip-RAx7aQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:30 INFO - Running setup.py (path:/tmp/pip-WcddZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:30 INFO - Running setup.py (path:/tmp/pip-i7xBiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:30 INFO - Running setup.py (path:/tmp/pip-FlKS0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:30 INFO - Running setup.py (path:/tmp/pip-MEgPVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:30 INFO - Running setup.py (path:/tmp/pip-aejz3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:30 INFO - Running setup.py (path:/tmp/pip-kfUXUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:30 INFO - Running setup.py (path:/tmp/pip-t9axFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:30 INFO - Running setup.py install for manifestparser 03:01:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for mozcrash 03:01:31 INFO - Running setup.py install for mozdebug 03:01:31 INFO - Running setup.py install for mozdevice 03:01:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for mozfile 03:01:31 INFO - Running setup.py install for mozhttpd 03:01:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for mozinfo 03:01:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for mozInstall 03:01:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for mozleak 03:01:31 INFO - Running setup.py install for mozlog 03:01:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for moznetwork 03:01:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:32 INFO - Running setup.py install for mozprocess 03:01:32 INFO - Running setup.py install for mozprofile 03:01:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:32 INFO - Running setup.py install for mozrunner 03:01:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:32 INFO - Running setup.py install for mozscreenshot 03:01:32 INFO - Running setup.py install for moztest 03:01:32 INFO - Running setup.py install for mozversion 03:01:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:32 INFO - Cleaning up... 03:01:32 INFO - Return code: 0 03:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:32 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:32 INFO - Using env: {'DISPLAY': ':0', 03:01:32 INFO - 'HOME': '/home/cltbld', 03:01:32 INFO - 'LANG': 'en_US.UTF-8', 03:01:32 INFO - 'LANGUAGE': 'en_US:en', 03:01:32 INFO - 'LOGNAME': 'cltbld', 03:01:32 INFO - 'MAIL': '/var/mail/cltbld', 03:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:32 INFO - 'MOZ_NO_REMOTE': '1', 03:01:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:32 INFO - 'NO_EM_RESTART': '1', 03:01:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:32 INFO - 'PWD': '/builds/slave/test', 03:01:32 INFO - 'SHELL': '/bin/bash', 03:01:32 INFO - 'SHLVL': '1', 03:01:32 INFO - 'TERM': 'linux', 03:01:32 INFO - 'TMOUT': '86400', 03:01:32 INFO - 'USER': 'cltbld', 03:01:32 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:32 INFO - '_': '/tools/buildbot/bin/python'} 03:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:32 INFO - Running setup.py (path:/tmp/pip-BMfT3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:33 INFO - Running setup.py (path:/tmp/pip-m5p3Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:33 INFO - Running setup.py (path:/tmp/pip-jZwLsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:33 INFO - Running setup.py (path:/tmp/pip-QvNi36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:33 INFO - Running setup.py (path:/tmp/pip-WeKPGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:33 INFO - Running setup.py (path:/tmp/pip-EpJGj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:33 INFO - Running setup.py (path:/tmp/pip-mj3qpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:33 INFO - Running setup.py (path:/tmp/pip-J50QwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:33 INFO - Running setup.py (path:/tmp/pip-jvGxlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:33 INFO - Running setup.py (path:/tmp/pip-q52hQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:33 INFO - Running setup.py (path:/tmp/pip-qlXrtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:33 INFO - Running setup.py (path:/tmp/pip-IPzY9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:33 INFO - Running setup.py (path:/tmp/pip-gZBJPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:33 INFO - Running setup.py (path:/tmp/pip-0SdjFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:34 INFO - Running setup.py (path:/tmp/pip-Y4c4yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:34 INFO - Running setup.py (path:/tmp/pip-nxXM_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:34 INFO - Running setup.py (path:/tmp/pip-iB5sIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:36 INFO - Downloading blessings-1.6.tar.gz 03:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:36 INFO - Installing collected packages: blessings 03:01:36 INFO - Running setup.py install for blessings 03:01:36 INFO - Successfully installed blessings 03:01:36 INFO - Cleaning up... 03:01:36 INFO - Return code: 0 03:01:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:36 INFO - Reading from file tmpfile_stdout 03:01:36 INFO - Current package versions: 03:01:36 INFO - argparse == 1.2.1 03:01:36 INFO - blessings == 1.6 03:01:36 INFO - blobuploader == 1.2.4 03:01:36 INFO - docopt == 0.6.1 03:01:36 INFO - functools32 == 3.2.3-2 03:01:36 INFO - jsonschema == 2.5.1 03:01:36 INFO - manifestparser == 1.1 03:01:36 INFO - mozInstall == 1.12 03:01:36 INFO - mozcrash == 0.17 03:01:36 INFO - mozdebug == 0.1 03:01:36 INFO - mozdevice == 0.48 03:01:36 INFO - mozfile == 1.2 03:01:36 INFO - mozhttpd == 0.7 03:01:36 INFO - mozinfo == 0.9 03:01:36 INFO - mozleak == 0.1 03:01:36 INFO - mozlog == 3.2 03:01:36 INFO - moznetwork == 0.27 03:01:36 INFO - mozprocess == 0.23 03:01:36 INFO - mozprofile == 0.28 03:01:36 INFO - mozrunner == 6.12 03:01:36 INFO - mozscreenshot == 0.1 03:01:36 INFO - mozsystemmonitor == 0.3 03:01:36 INFO - moztest == 0.7 03:01:36 INFO - mozversion == 1.4 03:01:36 INFO - psutil == 3.1.1 03:01:36 INFO - requests == 1.2.3 03:01:36 INFO - wsgiref == 0.1.2 03:01:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:36 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:36 INFO - Using env: {'DISPLAY': ':0', 03:01:36 INFO - 'HOME': '/home/cltbld', 03:01:36 INFO - 'LANG': 'en_US.UTF-8', 03:01:36 INFO - 'LANGUAGE': 'en_US:en', 03:01:36 INFO - 'LOGNAME': 'cltbld', 03:01:36 INFO - 'MAIL': '/var/mail/cltbld', 03:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:36 INFO - 'MOZ_NO_REMOTE': '1', 03:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:36 INFO - 'NO_EM_RESTART': '1', 03:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:36 INFO - 'PWD': '/builds/slave/test', 03:01:36 INFO - 'SHELL': '/bin/bash', 03:01:36 INFO - 'SHLVL': '1', 03:01:36 INFO - 'TERM': 'linux', 03:01:36 INFO - 'TMOUT': '86400', 03:01:36 INFO - 'USER': 'cltbld', 03:01:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:36 INFO - '_': '/tools/buildbot/bin/python'} 03:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:01:36 INFO - Cleaning up... 03:01:36 INFO - Return code: 0 03:01:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:36 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 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:01:36 INFO - Using env: {'DISPLAY': ':0', 03:01:36 INFO - 'HOME': '/home/cltbld', 03:01:36 INFO - 'LANG': 'en_US.UTF-8', 03:01:36 INFO - 'LANGUAGE': 'en_US:en', 03:01:36 INFO - 'LOGNAME': 'cltbld', 03:01:36 INFO - 'MAIL': '/var/mail/cltbld', 03:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:36 INFO - 'MOZ_NO_REMOTE': '1', 03:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:36 INFO - 'NO_EM_RESTART': '1', 03:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:36 INFO - 'PWD': '/builds/slave/test', 03:01:36 INFO - 'SHELL': '/bin/bash', 03:01:36 INFO - 'SHLVL': '1', 03:01:36 INFO - 'TERM': 'linux', 03:01:36 INFO - 'TMOUT': '86400', 03:01:36 INFO - 'USER': 'cltbld', 03:01:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:36 INFO - '_': '/tools/buildbot/bin/python'} 03:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:37 INFO - Cleaning up... 03:01:37 INFO - Return code: 0 03:01:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9086b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf07350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, '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': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:01:37 INFO - Using env: {'DISPLAY': ':0', 03:01:37 INFO - 'HOME': '/home/cltbld', 03:01:37 INFO - 'LANG': 'en_US.UTF-8', 03:01:37 INFO - 'LANGUAGE': 'en_US:en', 03:01:37 INFO - 'LOGNAME': 'cltbld', 03:01:37 INFO - 'MAIL': '/var/mail/cltbld', 03:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:37 INFO - 'MOZ_NO_REMOTE': '1', 03:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:37 INFO - 'NO_EM_RESTART': '1', 03:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:37 INFO - 'PWD': '/builds/slave/test', 03:01:37 INFO - 'SHELL': '/bin/bash', 03:01:37 INFO - 'SHLVL': '1', 03:01:37 INFO - 'TERM': 'linux', 03:01:37 INFO - 'TMOUT': '86400', 03:01:37 INFO - 'USER': 'cltbld', 03:01:37 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:37 INFO - '_': '/tools/buildbot/bin/python'} 03:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:37 INFO - Downloading/unpacking flake8 03:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:01:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:01:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:01:39 INFO - no previously-included directories found matching 'docs/_build' 03:01:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:01:39 INFO - Running setup.py install for pep8 03:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:01:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:01:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:01:39 INFO - no previously-included directories found matching 'docs/_build' 03:01:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:01:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:01:40 INFO - Cleaning up... 03:01:40 INFO - Return code: 0 03:01:40 INFO - Running post-action listener: _resource_record_post_action 03:01:40 INFO - Running post-action listener: _start_resource_monitoring 03:01:40 INFO - Starting resource monitoring. 03:01:40 INFO - ##### 03:01:40 INFO - ##### Finished create-virtualenv step (success) 03:01:40 INFO - ##### 03:01:40 INFO - ##### 03:01:40 INFO - ##### Running install step. 03:01:40 INFO - ##### 03:01:40 INFO - Running pre-action listener: _resource_record_pre_action 03:01:40 INFO - Running main action method: install 03:01:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:40 INFO - Reading from file tmpfile_stdout 03:01:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:40 INFO - Reading from file tmpfile_stdout 03:01:40 INFO - Output received: 03:01:40 INFO - Usage: mozinstall [options] installer 03:01:40 INFO - Options: 03:01:40 INFO - -h, --help show this help message and exit 03:01:40 INFO - -d DEST, --destination=DEST 03:01:40 INFO - Directory to install application into. [default: 03:01:40 INFO - "/builds/slave/test"] 03:01:40 INFO - --app=APP Application being installed. [default: firefox] 03:01:40 INFO - mkdir: /builds/slave/test/build/application 03:01:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:01:56 INFO - Reading from file tmpfile_stdout 03:01:56 INFO - Output received: 03:01:56 INFO - /builds/slave/test/build/application/firefox/firefox 03:01:56 INFO - Running post-action listener: _resource_record_post_action 03:01:56 INFO - ##### 03:01:56 INFO - ##### Finished install step (success) 03:01:56 INFO - ##### 03:01:56 INFO - ##### 03:01:56 INFO - ##### Running run-tests step. 03:01:56 INFO - ##### 03:01:56 INFO - Running pre-action listener: _resource_record_pre_action 03:01:56 INFO - Running main action method: run_tests 03:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:56 INFO - Python 2.7.3 03:01:56 INFO - Return code: 0 03:01:56 INFO - grabbing minidump binary from tooltool 03:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:56 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 0xf961c0>, '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 0xf970c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf97550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:01:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:01:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:01:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:56 INFO - Return code: 0 03:01:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:01:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:01:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:01:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:01:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:01:57 INFO - Return code: 0 03:01:57 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/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:01:57 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/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:01:57 INFO - Using env: {'DISPLAY': ':0', 03:01:57 INFO - 'HOME': '/home/cltbld', 03:01:57 INFO - 'LANG': 'en_US.UTF-8', 03:01:57 INFO - 'LANGUAGE': 'en_US:en', 03:01:57 INFO - 'LOGNAME': 'cltbld', 03:01:57 INFO - 'MAIL': '/var/mail/cltbld', 03:01:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:57 INFO - 'MOZ_NO_REMOTE': '1', 03:01:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:57 INFO - 'NO_EM_RESTART': '1', 03:01:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:57 INFO - 'PWD': '/builds/slave/test', 03:01:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:57 INFO - 'SHELL': '/bin/bash', 03:01:57 INFO - 'SHLVL': '1', 03:01:57 INFO - 'TERM': 'linux', 03:01:57 INFO - 'TMOUT': '86400', 03:01:57 INFO - 'USER': 'cltbld', 03:01:57 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038', 03:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:57 INFO - '_': '/tools/buildbot/bin/python'} 03:01:57 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/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:01:57 INFO - mozversion application_buildid: 20160729182925 03:01:57 INFO - mozversion application_changeset: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 03:01:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:01:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:57 INFO - mozversion application_name: Firefox 03:01:57 INFO - mozversion application_remotingname: firefox-dev 03:01:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:57 INFO - mozversion application_vendor: Mozilla 03:01:57 INFO - mozversion application_version: 49.0a2 03:01:57 INFO - mozversion platform_buildid: 20160729182925 03:01:57 INFO - mozversion platform_changeset: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 03:01:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:57 INFO - mozversion platform_version: 49.0a2 03:01:57 INFO - using testdate: 1469872917 03:01:57 INFO - actual date: 1469872917 03:01:57 INFO - starting webserver on 'localhost:33808' 03:01:57 INFO - SUITE-START | Running 2 tests 03:01:57 INFO - TEST-START | dromaeo_css 03:01:57 INFO - operating with platform_type : linux_ 03:01:57 INFO - Initialising browser for dromaeo_css test... 03:01:57 INFO - TEST-INFO | started process 27229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoIocBW/profile http://localhost:33808/getInfo.html) 03:02:01 INFO - PROCESS | 27229 | __metrics Screen width/height:1600/1200 03:02:01 INFO - PROCESS | 27229 | colorDepth:24 03:02:01 INFO - PROCESS | 27229 | Browser inner width/height: 1024/697 03:02:01 INFO - PROCESS | 27229 | __metrics 03:02:02 INFO - PROCESS | 27229 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:02:02 ERROR - PROCESS | 27229 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:02:03 INFO - TEST-INFO | 27229: exit 0 03:02:03 INFO - Browser initialized. 03:02:03 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:03 INFO - TEST-INFO | started process 27323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoIocBW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:15 INFO - PROCESS | 27323 | RSS: Main: 145440768 03:02:15 INFO - PROCESS | 27323 | 03:03:05 INFO - PROCESS | 27323 | 1469872985913 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:05:14 INFO - PROCESS | 27323 | Cycle 1(1): loaded http://localhost:33808/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33808/tests/dromaeo/cssquery-ext.html) 03:05:15 INFO - PROCESS | 27323 | RSS: Main: 256720896 03:05:15 INFO - PROCESS | 27323 | 03:08:17 INFO - PROCESS | 27323 | Cycle 1(1): loaded http://localhost:33808/tests/dromaeo/cssquery-ext.html (next: http://localhost:33808/tests/dromaeo/cssquery-jquery.html) 03:08:18 INFO - PROCESS | 27323 | RSS: Main: 226480128 03:08:18 INFO - PROCESS | 27323 | 03:11:19 INFO - PROCESS | 27323 | Cycle 1(1): loaded http://localhost:33808/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33808/tests/dromaeo/cssquery-mootools.html) 03:11:20 INFO - PROCESS | 27323 | RSS: Main: 233283584 03:11:20 INFO - PROCESS | 27323 | 03:14:22 INFO - PROCESS | 27323 | Cycle 1(1): loaded http://localhost:33808/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33808/tests/dromaeo/cssquery-prototype.html) 03:14:23 INFO - PROCESS | 27323 | RSS: Main: 225587200 03:14:23 INFO - PROCESS | 27323 | 03:17:25 INFO - PROCESS | 27323 | Cycle 1(1): loaded http://localhost:33808/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33808/tests/dromaeo/cssquery-yui.html) 03:17:26 INFO - PROCESS | 27323 | RSS: Main: 218230784 03:17:26 INFO - PROCESS | 27323 | 03:20:28 INFO - PROCESS | 27323 | Cycle 1(1): loaded http://localhost:33808/tests/dromaeo/cssquery-yui.html (next: http://localhost:33808/tests/dromaeo/cssquery-dojo.html) 03:20:29 INFO - PROCESS | 27323 | RSS: Main: 259948544 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | __start_tp_report 03:20:29 INFO - PROCESS | 27323 | _x_x_mozilla_page_load 03:20:29 INFO - PROCESS | 27323 | _x_x_mozilla_page_load_details 03:20:29 INFO - PROCESS | 27323 | |i|pagename|runs| 03:20:29 INFO - PROCESS | 27323 | |0;dojo.html;3258.7393008559316;3330.4837750148727;3332.383509050826;3350.6481819408946;3376.274101068271;5422.132458806587;5442.319710036246;5458.235846981426;5484.862878428041;5485.588580856437;5464.180372944061;5501.862453438656;5508.14623733321;5517.2551705519745;5540.86147846303;4958.281049247857;5026.070177017245;5042.420121685998;5083.034223497542;5116.411612664554;4927.704337739747;4987.875303117433;4994.750262486879;5010.298558201855;5040.294358789773;4975.0796102721115;5016.548510634042;5084.872878178038;5089.974550127245;5114.590832733373;5009.724465154415;5041.344625198737;5078.720670363128;5083.262926875601;5097.3118628984985;4848.781804818792;4911.607071434277;4953.0341583391255;4957.231629097496;4996.150654388763;5377.6504527206;5418.187271909206;5432.483914028161;5445.754941027623;5464.808731694372;5707;5867.237259156283;5871.647701137903;5872.295324561037;5873.412658734135;5831.650100993954;5858.384869588717;5862.355140934494;5950.494207992326;5970.13433052205;5560.888782224333;5594.608377413582;5596.636218645775;5597.384287728347;5599.6640201587625;5818.127280907897;5962.672053037038;6025.78909738157;6066.848328791815;6078.422549857756;5787.536997040227;5836.445537673914;5843.211166492554;5883.558733095035;5884.764609415575;5932.911006334908;5940.940590594038;5962.1056841473255;5967.075103358986;5969.224000879858;5644.520215781622;5794.82615521535;5852.853678658068;5913.822585322448;5923.348431672513;5906.7637294508595;5910.497607703393;5912.970435147883;5917.585768996215;5932.436418540232;4530.456345238593;4572.657050721209;4574.222382195035;4576.702514336557;4598.425196850394;4001.519817621846;4009.67922566194;4055.1078762672164;4064.878053658395;4065.8170382332864;5591.608587398924;5599.804006859822;5608.046632072557;5611.242482264924;5617.101263797855;5891;5893.970530147408;5898.705064746746;5899.76400943966;5935.673537955454;5394.703291319015;5503.477169668875;5554.166874968706;5564.221009058735;5596.132599447011;5043.697378157322;5043.773030213649;5044.924326135111;5053.69677819332;5075.695458272442;6017.157597936292;6078.817635470943;6125.509959203253;6138.202033735675;6138.202033735675;5808.7095645218415;5838.678872661874;5868.706564671664;5926.436988486087;5947.762089516371;6179.227596550431;6189.84525386869;6192.473639740492;6196.287426945959;6200.534959877937;5684.971575142098;5713.428657134254;5818.272715910512;5826.184334193301;5833.241678581757;5808.738606762621;5915.763369465173;5919.881602367843;5925.762969481172;5957.046872500379;5747.19539264495;5751.913721294268;5753.798617048383;5755.65466072037;5765.048766953573;5603.74783134768;5686.829395118153;5737.942620573908;5739.598228123991;5758.193852860519;5694.402087780723;5717.514011308919;5727.570432217517;5728.3985181557;5752.9137062943255;5808.215890854681;5824.825255242349;5849.883002339844;5852.239208902815;5857.384974577608;5523.917141242965;5558.388577256579;5618.438156184349;5664.235328230801;5694.658320500783 03:20:29 INFO - PROCESS | 27323 | |1;ext.html;568.5167607533597;596.7642781101465;600.6036016229286;641.3041849593191;656.1830521001355;32980;33406.66593334066;45317.999999999956;46955.53044469554;46971.76514117433;48674;49131.50868491314;49164.75417622916;49204.50795492044;49261;9052.411593246434;9191.35660503765;9198.172164505193;9203.723888283363;9206.907930920672;9002.684906028291;9074.546272686372;9112.544372781334;9120.087991200893;9125.452472851617;9158.587863546123;9165.266778657691;9186.678466253683;9189.448633082004;9192.540372981324;9000.59497322622;9089.27285817133;9106.089391060907;9135.36052476327;9138.406003609778;9018.684346047923;9071.818563628756;9077;9079.04670009646;9089.227415669677;45459.31811022837;45973.77013114913;46037.38869139564;46049.76975115136;46119;3577.338192434408;3777.660010599032;3780.4707340972286;3788.6211378861894;3880.47613572167;34635.307293853984;34761.82619086889;35191.4721279181;36036.63963360359;36594.8170259147;4475.619572336355;4482.731036137842;4513.27787553993;4629.768511574408;4706.952930470686;366731;367807;371290;371496.1425192884;372404;38128;39174.80412597976;39255;67593.66203169052;67997;40983.38524922128;41017.589824101975;41044.58955410468;41125.177496449905;41181;17487.300507979537;17758;17782;17785.28858845632;17787.911060444872;14405.495807646683;14599;14615.415383384758;14619.92690036543;14628.926855365655;9063.909360906306;9206.125418085272;9213.677521286723;9262.212711919563;9285.953570232106;3424.417848965632;3434.0899661589697;3513.5608048993877;3527.876524321636;3532.9293414131575;1039.6984874386494;1117.2179474367813;1196.85637723472;1201.3392634051236;1254.8368712067372;33285.16787080342;34915.82542087324;35077.29845403129;35187.64812351895;35525.111872202884;1142.2917790969486;1165.9475323610288;1179.9115066370057;1185.2177562808506;1262.5202423079172;18270.908645456686;18308.685690481034;18348.266069357076;18474.168662410222;18551.44345669632;154170;573589;575164;577119;579284;62871.37128628655;63260.683696581225;63737;63806.36193638097;63934.36065639377;1258.8363512743285;1355.3765267976842;1370.2121280263889;1427.7715565509468;1461.590754588717;33694.66305337013;34583.4812477808;35241;35595.32934489359;35884.461733074546;54244;59353.406465934786;60830.39169608247;60937.39062609317;66939;537827;538025;539988;540016;542141;38917.80541097277;40427.59572404238;45809.77095114503;46972;47451.28823067726;53777.73111134419;54034.729826350616;54339.72830135824;54432.455675444326;54776;43769;44598.108037838414;44894;45382.31926521171;45470.54529454663;43579.34630980474;43743.34384984292;44567.55432445634;45076.549234507234;45737.54262457466;43358.78320608377;43929.56070439255;44203.33694994514;44580.33129503125;45387.77306113448;37891.81054094712;38446.42330365103;38546.6145338543;38830.223395531364;38920.6107938917;28711;28884.133475995754;29085.23690249423;29591.704082959757;29652.406951861274 03:20:29 INFO - PROCESS | 27323 | |2;jquery.html;427.1137389915924;433.9587739164778;434.16856719382383;435.4984850108632;443.7315424168376;18282.90858545707;18557;19134;19731.309404170857;19747.407577772654;18263;18703.625927481444;18806.999999999967;19095.14071866766;19117.617647647072;5738.713064346787;6008.068749343859;6024.246969128859;6027.126066720323;6032.396760323976;4301.204277208726;4996.125678006352;5893.705314734268;5899.233099697055;6584.407403333699;6597.769078082268;6701.664916754167;6703.095082163894;6710.798676039727;6742;6486.221653401575;6646.1027761252335;6709.899351509707;6711.026901099338;6723.596584204964;4039.414284928669;5437.401885792559;5761.827145185651;5903.911441328383;5911.527077833763;23979.40051498709;24033;24035.519289614283;24207.03171873123;24289.87855060731;2275.6927814744963;2357.3399448154546;2406.2781165650235;2626.500964816679;3152.621685397767;10403.531841067095;10575;10890.074343680797;10967.616133435291;14085.436582536686;2034.918603255868;2081.8542702010864;2096.8322534197227;2116.1323857218467;2158.32012915931;80393;81279.59360203161;82927;82936;83152.58423707842;11639.06887449002;11731.47208375625;11789.233699809485;11815.468303926344;11817.940910295394;16627.334906603843;16775.496735097968;16788.412405565745;16800.579985500215;16803;8661.393702440895;8702.129787021247;8958.238549723215;8992.8651070234;9107.99436186057;8667.696630617898;8721.433106848035;8751.824963500694;8835.911640883638;8838.55807209637;4952.8514144575;5067.315912351785;5100.591952643779;5118;5129.12804823181;2045.059272734529;2077.6883467479997;2091.979080209209;2115.4394085567337;2135.316698656446;973.7273563402117;1037.64719995202;1105.4638500327335;1138.9715257118473;1146.2033886448837;19202;19427.32004379868;19660.21359145618;19742.210311587663;19857.702134467996;1023.150784848574;1080.8378743188584;1086.6957251969461;1095.638439315024;1138.6413279816832;10826.133909287395;10985.945070274598;11007.449627518592;11073.169512286611;11177.83233251502;432013;439769;455968;457032;457069;91915.5404222988;93952;94876;95796.52101739447;98245;1004.6282875336173;1010.802893435773;1026.2764750850633;1041.3491567770143;1048.580567772897;17326.740098898274;17396.39112631052;17560.91219543894;17651.911740441217;17895.552611184823;40363.3945490812;40463.595364045985;41039.794801025804;41572.79213603913;42673.573264268205;53866;58240.70879645574;58791.4120858786;58840.411595883495;59212.407875922414;8614.095519970564;8622.094680058504;8674.349423793366;8790.824183516423;8843.425177363451;9340.206082483064;12515.74968500643;12832.55086071983;12869.485220591072;12873.485060597472;11141.164412669244;11288.774224515299;11301.208915375844;11532.135089868125;11907.52369905194;11443.485043173243;11447.484863181011;11450.427478626201;11506.827397588875;11747.353895535827;11472.852714728795;11740.295582265091;11804;12063.879361206276;12112.636620901387;8496.660133594834;8692.52191129494;8712.738617841474;8785.121487851164;8793.956030219808;11152.609658661908;11226.943865280948;11507.597234096766;11720.355380454157;11732.061435085343 03:20:29 INFO - PROCESS | 27323 | |3;mootools.html;1325.4631874090996;1396.3646540823922;1412.6327154939713;1479.4156308258232;1481.8592233737795;9027.819443611124;9399.483028433433;9404.10660987206;9582.089701478348;9586.424814511134;9362.391444556099;9561.521923903794;9564.282678799083;9565.330426870121;9567.904320956788;2499.1752721601874;2523.621456781488;2524.936876578082;2525.9621105683427;2527.431327951215;2489.103922587867;2528.089887640448;2528.1657053172503;2532.316274605855;2541.59334506479;2403.7476065013184;2432.3554258121612;2486.316263027664;2487.465194983078;2489.265666628349;2364.255259593231;2372.9050837966547;2382.3805810489225;2446.7186273578586;2453.6810214671978;2366.3255972048055;2390.725066617344;2396.628522579003;2396.772306630867;2403.375122468153;8803.955980220058;8978.50618215998;9358.344915855892;9383.343165978384;9403.29475289356;5455.8363249102595;5580.441955804427;5581.441855814427;5583.246261754652;5595.860103497404;3700.648438398348;3730.682891954187;3798.3352913240074;3803.2583646189005;3832.4442955771256;1037.8806437259736;1163.6974386659444;1189.328029663246;1190.3691043746828;1195.842974547196;13240.735185296337;13318.600441986659;13322.733545329136;13455;13476.865231347758;4752.5485078917445;4809;4927.039227350704;4932.580730637864;4946.678465899777;10640.787184256425;10642.627508037182;10652.786944261226;10680.732981675521;10696.465176741132;5536.363318218457;5543.805966791143;5544.667319960815;5550.861228469321;5555.944440555542;4339.696221264481;4718.834840780556;4860.538248866352;4869.196582563837;4974.801007959641;3955.7033222508426;4058.898527536835;4064.1262128642484;4140.426637877647;4187.916241675211;1184.774892770388;1194.2774621353956;1195.1633856300625;1200.609801814414;1202;820.3754680951341;899.6266549381933;970.825251454745;978.4080631218011;994.4679596415883;5865.73604187804;6130.264368275835;6154.661493617805;6172.506199504029;6261.311255761862;442.6591524526097;632.9746810127543;1072.3351522056219;1084.967450976472;1116.7989761842946;3989.9800500997308;4002.8198731057173;4055.3105971984833;4058.6347228748964;4063.4514340563646;11934.820977685167;12131.090168237417;12256.20334678261;12274.32491212974;12303.384830758427;12058.276503409823;12068.336241506626;12076.577319793942;12083.697907552205;12099.697507562381;8593.570321483901;8824.294056475466;8839.46963182211;8982.562789953576;8987.41581797181;6086.847828804315;6130.509559235161;6139.815805525842;6166.845828854314;6274.874502510015;10723.142148628247;11147.72130696739;11219.831702524305;11240.999999999673;11249.100071994384;11122;11286.379249141048;11308.264962777723;11324.37715925632;11424.257423267794;6942.548734332252;7151.141862976476;7649.923500765144;8036.116027237116;8209.302209312274;9205.493697846683;9220.907790922005;9306.813863722824;9310;9316.767080823034;8091.312238459562;8262.132476089924;8277.379196560403;8378.49729016261;8386.412951093365;7829.960850195713;8058.677652893819;8133.877991830245;8182.7545173644885;8234.176582341717;7147.178074521288;7211.42308615299;7265.782026539212;7460.253974602496;7479.214682458263;5257.132573125392;5338.878835444445;5451.836444906659;5453.209284653623;5455.781768729206;5129.2306154075695;5322.175062865107;5422;5428.6199966002005;5480.205370221216 03:20:29 INFO - PROCESS | 27323 | |4;prototype.html;342.4195987800678;343.833240878174;346.8179205916895;351.04865813645023;351.32545512615775;5337.813176538822;5347.91978120328;5351.705656188903;5365.704886231256;5366.356037275523;5296.576273898088;5299.470052994699;5303.814366497173;5306;5352.652077614952;4016.0963783148777;4087.9182416351655;4200.453940987667;4219.3881887126345;4270.850520231793;4157.230912281222;4180.770057646828;4184.456020717303;4223.51429585597;4265.936010959838;4000.6199411055904;4130.772807495587;4172.666186705056;4183.309753890604;4186.832526698929;4094.1985526368453;4160.396742472355;4171.20747058058;4194.3498757692605;4195.895102622459;3694.9445758313645;3698.779402797097;3699.112213068871;3722.236941427001;3731.9253614927547;5065.924011139836;5146.382434107931;5146.485351464861;5146.9485305146845;5152.124138896396;4520.819167233307;4711.892705214272;4724.582625212457;4740.597049250818;4780.235162374003;5588.273524441837;5596.692181929992;5598.356189038272;5601.271834661508;5604.635698679573;4018.9196216075807;4032.536258330271;4032.983508245877;4075.572064933169;4095.877123686294;5584.972075139639;5755.827325180251;5809.767609295665;5815.772246403202;5821.27234095738;4103.938440923389;4127.133302006612;4132.483439570054;4133.4626498555;4147.875563733093;4188.214837133272;4207.590457196873;4211.410402543646;4231.978840105779;4234.745915245096;4550.840720574764;4761.666683332202;4783.689060211075;4810.306228512044;4825;5533.505953392561;5540.584456165804;5574.721263936868;5578.684845788952;5613.242212301368;1473.668424604477;1478.578605097542;1483.592012196633;1484.7401704701633;1486.8959172857797;979.3976704326788;987.2398253344884;996.1283876607968;998.201438848918;999.6751055906714;5408.45915408456;5422.43064478232;5438.945610543923;5444.40111587725;5510.6969116698165;5717.4854263115685;5771.336296325976;5785.88428231433;5788.944924551808;5827.24245848037;5275.695718044819;5323.893522129536;5423.2136340230445;5437.34751829783;5460.96241714073;3034.8786048558254;3037.817730936151;3043.4934707320017;3053.7937514681576;3054.1448394449144;5719.48524632785;5880.441358071062;5943.167956486095;5963.5527335450015;6000.729967151488;5730.914036289376;5754.338251101177;5762.510186634183;5776.3068431787615;5797.449242321973;5844.415558444121;5943.138244954457;5961.821145365646;5962.0460726284455;6001.159837622737;5474.507294343528;5737.512311453406;5776.111944027986;5785.56608254391;5787.334456537384;5609.186667933045;5623.409541998031;5646.632968857011;5692.601517893708;5706.861226325422;5829.417058294137;5989.790357337472;6011.128386384037;6089.847753806191;6106.877862442815;4059.5534491206536;4072.9389059163545;4088.4889388826396;4088.918221635491;4089.8752842967233;5562.582806289463;5631.2960879890015;5721.7425215866215;5781.190633311424;5842.941570584239;5617.466340697627;5621.4659607337235;5639.210510528447;5646.435356464321;5669.31968163823;5635.492805647511;5664.688442135557;5675.460831221028;5677.602567820378;5682.659040457585;5257.4216836147225;5417.589172818216;5579.442055794388;5586.217929489956;5587.0388693992245;5668.03643380618;5683.8010669626365;5685.800996965087;5686.77252909879;5699.943000569941;5530.889382212253;5546.4453554644215;5606.803761868315;5616.803411880728;5659.434056594308 03:20:29 INFO - PROCESS | 27323 | |5;yui.html;742.1576510660399;757.2389748302954;761.7181642792168;787.8384931089128;792.1167897793957;15807.604809879758;16571.83428165718;16599.50201493957;16618.418355357564;16640.251188696493;14472.421103155888;14901.478448254285;14913.105213687186;15254.466093686724;15327.69344613107;720.7837648705394;835.3734698975768;861.9698310559131;869.6738722978882;876.1720174435161;874.422880898607;875.763543843162;876.0494863073573;877.1842186766305;878.5782824244367;915.7619019055061;916.7891384981458;917.7980844214262;917.9082091790801;917.9403338782957;594.7977687586241;598.0700011482144;604.3563604760916;753.0473950452655;906.5875026862761;437.34835095707484;438.3753151758731;439.14147840970827;440.6871121503736;440.73555866479853;15650.921745391313;15659.21703914811;15667.451639192574;15668;15668.294926728211;14471.204083775388;15032.699346013127;15074.698506029928;15105.093694378373;15238.390464381411;12720.109592328541;12894.548690795871;13121.278329691862;13376.665583360396;14033.929830350782;4401.867943961686;4628.953710462886;4697.718136911796;4720.575148236675;4776.785044672998;78048.21951780467;78226.6088669553;78865;79221.60389198016;79440;10749.032587067202;10790.136789056856;10880.455977201109;11892.124014440818;12329.876701232874;21889.67165492519;22183.889080554818;22279;22287;22287.219947301768;8404.285635721033;8576.914230857736;8656.567171641393;8737.388382813142;8828.161324674145;4769.332293478916;4969.204927211629;4981.775820088104;5100.336956195745;5352.036633405946;3832.674222691047;3929.548101968253;3932.4073750131242;3937.9606203938165;3939.487866577384;627.6046090962665;637.346719612401;645.5481163185789;650.7982525417136;661.7253839656585;716.30160593421;753.5102183580607;775.2634996753108;777.7355699062346;809.3323008517996;10325.380477171244;10670.946645266878;10707.678769636923;10846.566137354417;10896.455177241265;648.6918713610997;674.3088334457123;706.3960313931515;764.6635480388611;770.3221165374547;4966.875828104254;5158.123119069767;5189.429162792166;5282.867928301823;5343.62594618373;119684;119868;119913.40043299728;120433;120934;74370;78625.60687196642;101471;105029;105645.47177264064;67615.66192169007;91785.54107229422;92307;92921.53539232396;93216.53391732997;10086;10376.688699339033;10392.37645741258;10393.740156496147;10465.47672616351;53950.19074713961;54559.45440545703;55539.44460555504;55667;56944;67156.6642166786;67829.66085169543;68064;69551.65224173847;71098.28901711124;13391;13795.517156899461;13911.165330080228;14123.929380353033;14272.785908211177;15020.324085415963;15519.146446945075;15551.455699050479;16489.587760306087;16842.1578921051;14494.492692755703;14598.467160947948;14749.631259218606;14766.778498322748;16112.516624501284;14830.555083347517;14925.253737312874;15259.694806103595;15440.691186176438;15499.14754688503;14932.552023439313;14945.925270373578;14987.850121498646;15133.167675777939;15220.847791522387;12295.385230738606;12340.81488777651;12687.936560317139;12864.935675321563;13023.934880325538;12455.252684838939;12580.937095314464;12806.103572750191;12887.226766394046;12953.676158096123 03:20:29 INFO - PROCESS | 27323 | __end_tp_report 03:20:29 INFO - PROCESS | 27323 | __start_cc_report 03:20:29 INFO - PROCESS | 27323 | _x_x_mozilla_cycle_collect,926 03:20:29 INFO - PROCESS | 27323 | __end_cc_report 03:20:29 INFO - PROCESS | 27323 | __startTimestamp1469874029033__endTimestamp 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | ------- Summary: start ------- 03:20:29 INFO - PROCESS | 27323 | Number of tests: 6 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | [#0] dojo.html Cycles:165 Average:5480.21 Median:5686.83 stddev:603.60 (10.6%) stddev-sans-first:579.75 03:20:29 INFO - PROCESS | 27323 | Values: 3258.7 3330.5 3332.4 3350.6 3376.3 5422.1 5442.3 5458.2 5484.9 5485.6 5464.2 5501.9 5508.1 5517.3 5540.9 4958.3 5026.1 5042.4 5083.0 5116.4 4927.7 4987.9 4994.8 5010.3 5040.3 4975.1 5016.5 5084.9 5090.0 5114.6 5009.7 5041.3 5078.7 5083.3 5097.3 4848.8 4911.6 4953.0 4957.2 4996.2 5377.7 5418.2 5432.5 5445.8 5464.8 5707.0 5867.2 5871.6 5872.3 5873.4 5831.7 5858.4 5862.4 5950.5 5970.1 5560.9 5594.6 5596.6 5597.4 5599.7 5818.1 5962.7 6025.8 6066.8 6078.4 5787.5 5836.4 5843.2 5883.6 5884.8 5932.9 5940.9 5962.1 5967.1 5969.2 5644.5 5794.8 5852.9 5913.8 5923.3 5906.8 5910.5 5913.0 5917.6 5932.4 4530.5 4572.7 4574.2 4576.7 4598.4 4001.5 4009.7 4055.1 4064.9 4065.8 5591.6 5599.8 5608.0 5611.2 5617.1 5891.0 5894.0 5898.7 5899.8 5935.7 5394.7 5503.5 5554.2 5564.2 5596.1 5043.7 5043.8 5044.9 5053.7 5075.7 6017.2 6078.8 6125.5 6138.2 6138.2 5808.7 5838.7 5868.7 5926.4 5947.8 6179.2 6189.8 6192.5 6196.3 6200.5 5685.0 5713.4 5818.3 5826.2 5833.2 5808.7 5915.8 5919.9 5925.8 5957.0 5747.2 5751.9 5753.8 5755.7 5765.0 5603.7 5686.8 5737.9 5739.6 5758.2 5694.4 5717.5 5727.6 5728.4 5752.9 5808.2 5824.8 5849.9 5852.2 5857.4 5523.9 5558.4 5618.4 5664.2 5694.7 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | [#1] ext.html Cycles:180 Average:63318.49 Median:44589.22 stddev:128466.32 (288.1%) stddev-sans-first:128740.31 03:20:29 INFO - PROCESS | 27323 | Values: 568.5 596.8 600.6 641.3 656.2 32980.0 33406.7 45318.0 46955.5 46971.8 48674.0 49131.5 49164.8 49204.5 49261.0 9052.4 9191.4 9198.2 9203.7 9206.9 9002.7 9074.5 9112.5 9120.1 9125.5 9158.6 9165.3 9186.7 9189.4 9192.5 9000.6 9089.3 9106.1 9135.4 9138.4 9018.7 9071.8 9077.0 9079.0 9089.2 45459.3 45973.8 46037.4 46049.8 46119.0 3577.3 3777.7 3780.5 3788.6 3880.5 34635.3 34761.8 35191.5 36036.6 36594.8 4475.6 4482.7 4513.3 4629.8 4707.0 366731.0 367807.0 371290.0 371496.1 372404.0 38128.0 39174.8 39255.0 67593.7 67997.0 40983.4 41017.6 41044.6 41125.2 41181.0 17487.3 17758.0 17782.0 17785.3 17787.9 14405.5 14599.0 14615.4 14619.9 14628.9 9063.9 9206.1 9213.7 9262.2 9286.0 3424.4 3434.1 3513.6 3527.9 3532.9 1039.7 1117.2 1196.9 1201.3 1254.8 33285.2 34915.8 35077.3 35187.6 35525.1 1142.3 1165.9 1179.9 1185.2 1262.5 18270.9 18308.7 18348.3 18474.2 18551.4 154170.0 573589.0 575164.0 577119.0 579284.0 62871.4 63260.7 63737.0 63806.4 63934.4 1258.8 1355.4 1370.2 1427.8 1461.6 33694.7 34583.5 35241.0 35595.3 35884.5 54244.0 59353.4 60830.4 60937.4 66939.0 537827.0 538025.0 539988.0 540016.0 542141.0 38917.8 40427.6 45809.8 46972.0 47451.3 53777.7 54034.7 54339.7 54432.5 54776.0 43769.0 44598.1 44894.0 45382.3 45470.5 43579.3 43743.3 44567.6 45076.5 45737.5 43358.8 43929.6 44203.3 44580.3 45387.8 37891.8 38446.4 38546.6 38830.2 38920.6 28711.0 28884.1 29085.2 29591.7 29652.4 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | [#2] jquery.html Cycles:180 Average:28156.85 Median:24006.20 stddev:74237.62 (309.2%) stddev-sans-first:74416.68 03:20:29 INFO - PROCESS | 27323 | Values: 427.1 434.0 434.2 435.5 443.7 18282.9 18557.0 19134.0 19731.3 19747.4 18263.0 18703.6 18807.0 19095.1 19117.6 5738.7 6008.1 6024.2 6027.1 6032.4 4301.2 4996.1 5893.7 5899.2 6584.4 6597.8 6701.7 6703.1 6710.8 6742.0 6486.2 6646.1 6709.9 6711.0 6723.6 4039.4 5437.4 5761.8 5903.9 5911.5 23979.4 24033.0 24035.5 24207.0 24289.9 2275.7 2357.3 2406.3 2626.5 3152.6 10403.5 10575.0 10890.1 10967.6 14085.4 2034.9 2081.9 2096.8 2116.1 2158.3 80393.0 81279.6 82927.0 82936.0 83152.6 11639.1 11731.5 11789.2 11815.5 11817.9 16627.3 16775.5 16788.4 16800.6 16803.0 8661.4 8702.1 8958.2 8992.9 9108.0 8667.7 8721.4 8751.8 8835.9 8838.6 4952.9 5067.3 5100.6 5118.0 5129.1 2045.1 2077.7 2092.0 2115.4 2135.3 973.7 1037.6 1105.5 1139.0 1146.2 19202.0 19427.3 19660.2 19742.2 19857.7 1023.2 1080.8 1086.7 1095.6 1138.6 10826.1 10985.9 11007.4 11073.2 11177.8 432013.0 439769.0 455968.0 457032.0 457069.0 91915.5 93952.0 94876.0 95796.5 98245.0 1004.6 1010.8 1026.3 1041.3 1048.6 17326.7 17396.4 17560.9 17651.9 17895.6 40363.4 40463.6 41039.8 41572.8 42673.6 53866.0 58240.7 58791.4 58840.4 59212.4 8614.1 8622.1 8674.3 8790.8 8843.4 9340.2 12515.7 12832.6 12869.5 12873.5 11141.2 11288.8 11301.2 11532.1 11907.5 11443.5 11447.5 11450.4 11506.8 11747.4 11472.9 11740.3 11804.0 12063.9 12112.6 8496.7 8692.5 8712.7 8785.1 8794.0 11152.6 11226.9 11507.6 11720.4 11732.1 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | [#3] mootools.html Cycles:180 Average:6158.69 Median:4735.69 stddev:3625.61 (76.6%) stddev-sans-first:3617.59 03:20:29 INFO - PROCESS | 27323 | Values: 1325.5 1396.4 1412.6 1479.4 1481.9 9027.8 9399.5 9404.1 9582.1 9586.4 9362.4 9561.5 9564.3 9565.3 9567.9 2499.2 2523.6 2524.9 2526.0 2527.4 2489.1 2528.1 2528.2 2532.3 2541.6 2403.7 2432.4 2486.3 2487.5 2489.3 2364.3 2372.9 2382.4 2446.7 2453.7 2366.3 2390.7 2396.6 2396.8 2403.4 8804.0 8978.5 9358.3 9383.3 9403.3 5455.8 5580.4 5581.4 5583.2 5595.9 3700.6 3730.7 3798.3 3803.3 3832.4 1037.9 1163.7 1189.3 1190.4 1195.8 13240.7 13318.6 13322.7 13455.0 13476.9 4752.5 4809.0 4927.0 4932.6 4946.7 10640.8 10642.6 10652.8 10680.7 10696.5 5536.4 5543.8 5544.7 5550.9 5555.9 4339.7 4718.8 4860.5 4869.2 4974.8 3955.7 4058.9 4064.1 4140.4 4187.9 1184.8 1194.3 1195.2 1200.6 1202.0 820.4 899.6 970.8 978.4 994.5 5865.7 6130.3 6154.7 6172.5 6261.3 442.7 633.0 1072.3 1085.0 1116.8 3990.0 4002.8 4055.3 4058.6 4063.5 11934.8 12131.1 12256.2 12274.3 12303.4 12058.3 12068.3 12076.6 12083.7 12099.7 8593.6 8824.3 8839.5 8982.6 8987.4 6086.8 6130.5 6139.8 6166.8 6274.9 10723.1 11147.7 11219.8 11241.0 11249.1 11122.0 11286.4 11308.3 11324.4 11424.3 6942.5 7151.1 7649.9 8036.1 8209.3 9205.5 9220.9 9306.8 9310.0 9316.8 8091.3 8262.1 8277.4 8378.5 8386.4 7830.0 8058.7 8133.9 8182.8 8234.2 7147.2 7211.4 7265.8 7460.3 7479.2 5257.1 5338.9 5451.8 5453.2 5455.8 5129.2 5322.2 5422.0 5428.6 5480.2 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | [#4] prototype.html Cycles:180 Average:4728.81 Median:5422.82 stddev:1380.39 (25.5%) stddev-sans-first:1344.43 03:20:29 INFO - PROCESS | 27323 | Values: 342.4 343.8 346.8 351.0 351.3 5337.8 5347.9 5351.7 5365.7 5366.4 5296.6 5299.5 5303.8 5306.0 5352.7 4016.1 4087.9 4200.5 4219.4 4270.9 4157.2 4180.8 4184.5 4223.5 4265.9 4000.6 4130.8 4172.7 4183.3 4186.8 4094.2 4160.4 4171.2 4194.3 4195.9 3694.9 3698.8 3699.1 3722.2 3731.9 5065.9 5146.4 5146.5 5146.9 5152.1 4520.8 4711.9 4724.6 4740.6 4780.2 5588.3 5596.7 5598.4 5601.3 5604.6 4018.9 4032.5 4033.0 4075.6 4095.9 5585.0 5755.8 5809.8 5815.8 5821.3 4103.9 4127.1 4132.5 4133.5 4147.9 4188.2 4207.6 4211.4 4232.0 4234.7 4550.8 4761.7 4783.7 4810.3 4825.0 5533.5 5540.6 5574.7 5578.7 5613.2 1473.7 1478.6 1483.6 1484.7 1486.9 979.4 987.2 996.1 998.2 999.7 5408.5 5422.4 5438.9 5444.4 5510.7 5717.5 5771.3 5785.9 5788.9 5827.2 5275.7 5323.9 5423.2 5437.3 5461.0 3034.9 3037.8 3043.5 3053.8 3054.1 5719.5 5880.4 5943.2 5963.6 6000.7 5730.9 5754.3 5762.5 5776.3 5797.4 5844.4 5943.1 5961.8 5962.0 6001.2 5474.5 5737.5 5776.1 5785.6 5787.3 5609.2 5623.4 5646.6 5692.6 5706.9 5829.4 5989.8 6011.1 6089.8 6106.9 4059.6 4072.9 4088.5 4088.9 4089.9 5562.6 5631.3 5721.7 5781.2 5842.9 5617.5 5621.5 5639.2 5646.4 5669.3 5635.5 5664.7 5675.5 5677.6 5682.7 5257.4 5417.6 5579.4 5586.2 5587.0 5668.0 5683.8 5685.8 5686.8 5699.9 5530.9 5546.4 5606.8 5616.8 5659.4 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - PROCESS | 27323 | [#5] yui.html Cycles:180 Average:21329.29 Median:3935.18 stddev:30020.77 (762.9%) stddev-sans-first:30065.19 03:20:29 INFO - PROCESS | 27323 | Values: 742.2 757.2 761.7 787.8 792.1 15807.6 16571.8 16599.5 16618.4 16640.3 14472.4 14901.5 14913.1 15254.5 15327.7 720.8 835.4 862.0 869.7 876.2 874.4 875.8 876.0 877.2 878.6 915.8 916.8 917.8 917.9 917.9 594.8 598.1 604.4 753.0 906.6 437.3 438.4 439.1 440.7 440.7 15650.9 15659.2 15667.5 15668.0 15668.3 14471.2 15032.7 15074.7 15105.1 15238.4 12720.1 12894.5 13121.3 13376.7 14033.9 4401.9 4629.0 4697.7 4720.6 4776.8 78048.2 78226.6 78865.0 79221.6 79440.0 10749.0 10790.1 10880.5 11892.1 12329.9 21889.7 22183.9 22279.0 22287.0 22287.2 8404.3 8576.9 8656.6 8737.4 8828.2 4769.3 4969.2 4981.8 5100.3 5352.0 3832.7 3929.5 3932.4 3938.0 3939.5 627.6 637.3 645.5 650.8 661.7 716.3 753.5 775.3 777.7 809.3 10325.4 10670.9 10707.7 10846.6 10896.5 648.7 674.3 706.4 764.7 770.3 4966.9 5158.1 5189.4 5282.9 5343.6 119684.0 119868.0 119913.4 120433.0 120934.0 74370.0 78625.6 101471.0 105029.0 105645.5 67615.7 91785.5 92307.0 92921.5 93216.5 10086.0 10376.7 10392.4 10393.7 10465.5 53950.2 54559.5 55539.4 55667.0 56944.0 67156.7 67829.7 68064.0 69551.7 71098.3 13391.0 13795.5 13911.2 14123.9 14272.8 15020.3 15519.1 15551.5 16489.6 16842.2 14494.5 14598.5 14749.6 14766.8 16112.5 14830.6 14925.3 15259.7 15440.7 15499.1 14932.6 14945.9 14987.9 15133.2 15220.8 12295.4 12340.8 12687.9 12864.9 13023.9 12455.3 12580.9 12806.1 12887.2 12953.7 03:20:29 INFO - PROCESS | 27323 | -------- Summary: end -------- 03:20:29 INFO - PROCESS | 27323 | 03:20:29 INFO - TEST-INFO | 27323: exit 0 03:20:29 INFO - TEST-OK | dromaeo_css | took 1112331ms 03:20:29 INFO - TEST-START | kraken 03:20:29 INFO - operating with platform_type : linux_ 03:20:29 INFO - Initialising browser for kraken test... 03:20:29 INFO - TEST-INFO | started process 27513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXdB1IR/profile http://localhost:33808/getInfo.html) 03:20:33 INFO - PROCESS | 27513 | __metrics Screen width/height:1600/1200 03:20:33 INFO - PROCESS | 27513 | colorDepth:24 03:20:33 INFO - PROCESS | 27513 | Browser inner width/height: 1024/697 03:20:33 INFO - PROCESS | 27513 | __metrics 03:20:34 INFO - PROCESS | 27513 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:20:34 ERROR - PROCESS | 27513 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:20:35 INFO - TEST-INFO | 27513: exit 0 03:20:35 INFO - Browser initialized. 03:20:35 INFO - Running cycle 1/1 for kraken test... 03:20:35 INFO - TEST-INFO | started process 27606 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXdB1IR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:47 INFO - PROCESS | 27606 | RSS: Main: 145387520 03:20:47 INFO - PROCESS | 27606 | 03:20:49 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33808/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:50 INFO - PROCESS | 27606 | RSS: Main: 194576384 03:20:50 INFO - PROCESS | 27606 | 03:20:53 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33808/tests/kraken/driver.html?testName=audio-dft) 03:20:53 INFO - PROCESS | 27606 | RSS: Main: 191672320 03:20:53 INFO - PROCESS | 27606 | 03:20:56 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33808/tests/kraken/driver.html?testName=audio-fft) 03:20:57 INFO - PROCESS | 27606 | RSS: Main: 279228416 03:20:57 INFO - PROCESS | 27606 | 03:20:59 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33808/tests/kraken/driver.html?testName=audio-oscillator) 03:21:00 INFO - PROCESS | 27606 | RSS: Main: 187719680 03:21:00 INFO - PROCESS | 27606 | 03:21:02 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33808/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:02 INFO - PROCESS | 27606 | RSS: Main: 188350464 03:21:02 INFO - PROCESS | 27606 | 03:21:05 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33808/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:06 INFO - PROCESS | 27606 | RSS: Main: 223289344 03:21:06 INFO - PROCESS | 27606 | 03:21:09 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33808/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:10 INFO - PROCESS | 27606 | RSS: Main: 223948800 03:21:10 INFO - PROCESS | 27606 | 03:21:13 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33808/tests/kraken/driver.html?testName=json-parse-financial) 03:21:13 INFO - PROCESS | 27606 | RSS: Main: 224182272 03:21:13 INFO - PROCESS | 27606 | 03:21:15 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33808/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:21:15 INFO - PROCESS | 27606 | RSS: Main: 201527296 03:21:15 INFO - PROCESS | 27606 | 03:21:17 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:21:18 INFO - PROCESS | 27606 | RSS: Main: 201797632 03:21:18 INFO - PROCESS | 27606 | 03:21:20 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:21:20 INFO - PROCESS | 27606 | RSS: Main: 209293312 03:21:20 INFO - PROCESS | 27606 | 03:21:23 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:23 INFO - PROCESS | 27606 | RSS: Main: 214364160 03:21:23 INFO - PROCESS | 27606 | 03:21:26 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:27 INFO - PROCESS | 27606 | RSS: Main: 214372352 03:21:27 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | Cycle 1(1): loaded http://localhost:33808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33808/tests/kraken/driver.html?testName=ai-astar) 03:21:28 INFO - PROCESS | 27606 | RSS: Main: 257429504 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | __start_tp_report 03:21:28 INFO - PROCESS | 27606 | _x_x_mozilla_page_load 03:21:28 INFO - PROCESS | 27606 | _x_x_mozilla_page_load_details 03:21:28 INFO - PROCESS | 27606 | |i|pagename|runs| 03:21:28 INFO - PROCESS | 27606 | |0;ai-astar;112;107;119;110;111;111;113;110;112;112 03:21:28 INFO - PROCESS | 27606 | |1;audio-beat-detection;155;122;129;173;125;130;124;147;177;130 03:21:28 INFO - PROCESS | 27606 | |2;audio-dft;160;166;160;162;161;161;161;160;159;160 03:21:28 INFO - PROCESS | 27606 | |3;audio-fft;77;75;77;116;76;76;75;75;104;75 03:21:28 INFO - PROCESS | 27606 | |4;audio-oscillator;85;85;115;84;84;84;118;85;84;123 03:21:28 INFO - PROCESS | 27606 | |5;imaging-gaussian-blur;117;116;116;117;117;116;117;116;117;116 03:21:28 INFO - PROCESS | 27606 | |6;imaging-darkroom;146;145;146;146;145;146;146;146;148;147 03:21:28 INFO - PROCESS | 27606 | |7;imaging-desaturate;95;95;95;96;95;96;95;95;95;95 03:21:28 INFO - PROCESS | 27606 | |8;json-parse-financial;49;50;49;51;52;50;52;51;51;52 03:21:28 INFO - PROCESS | 27606 | |9;json-stringify-tinderbox;61;60;61;60;60;60;60;61;61;60 03:21:28 INFO - PROCESS | 27606 | |10;stanford-crypto-aes;76;81;77;76;77;78;84;77;117;78 03:21:28 INFO - PROCESS | 27606 | |11;stanford-crypto-ccm;118;118;118;116;128;126;117;119;128;119 03:21:28 INFO - PROCESS | 27606 | |12;stanford-crypto-pbkdf2;158;154;156;152;154;155;156;161;151;152 03:21:28 INFO - PROCESS | 27606 | |13;stanford-crypto-sha256-iterative;63;63;62;61;61;60;61;60;60;59 03:21:28 INFO - PROCESS | 27606 | __end_tp_report 03:21:28 INFO - PROCESS | 27606 | __start_cc_report 03:21:28 INFO - PROCESS | 27606 | _x_x_mozilla_cycle_collect,1627 03:21:28 INFO - PROCESS | 27606 | __end_cc_report 03:21:28 INFO - PROCESS | 27606 | __startTimestamp1469874088966__endTimestamp 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | ------- Summary: start ------- 03:21:28 INFO - PROCESS | 27606 | Number of tests: 14 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:3.06 (2.7%) stddev-sans-first:3.24 03:21:28 INFO - PROCESS | 27606 | Values: 112.0 107.0 119.0 110.0 111.0 111.0 113.0 110.0 112.0 112.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:138.50 stddev:20.67 (14.9%) stddev-sans-first:21.31 03:21:28 INFO - PROCESS | 27606 | Values: 155.0 122.0 129.0 173.0 125.0 130.0 124.0 147.0 177.0 130.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.94 (1.2%) stddev-sans-first:2.03 03:21:28 INFO - PROCESS | 27606 | Values: 160.0 166.0 160.0 162.0 161.0 161.0 161.0 160.0 159.0 160.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.50 stddev:14.74 (19.5%) stddev-sans-first:15.49 03:21:28 INFO - PROCESS | 27606 | Values: 77.0 75.0 77.0 116.0 76.0 76.0 75.0 75.0 104.0 75.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.00 stddev:16.65 (19.8%) stddev-sans-first:17.29 03:21:28 INFO - PROCESS | 27606 | Values: 85.0 85.0 115.0 84.0 84.0 84.0 118.0 85.0 84.0 123.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 03:21:28 INFO - PROCESS | 27606 | Values: 117.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 03:21:28 INFO - PROCESS | 27606 | Values: 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 148.0 147.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:21:28 INFO - PROCESS | 27606 | Values: 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.16 (2.3%) stddev-sans-first:1.05 03:21:28 INFO - PROCESS | 27606 | Values: 49.0 50.0 49.0 51.0 52.0 50.0 52.0 51.0 51.0 52.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 03:21:28 INFO - PROCESS | 27606 | Values: 61.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 61.0 60.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:77.50 stddev:12.51 (16.1%) stddev-sans-first:13.07 03:21:28 INFO - PROCESS | 27606 | Values: 76.0 81.0 77.0 76.0 77.0 78.0 84.0 77.0 117.0 78.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#11] stanford-crypto-ccm Cycles:10 Average:120.70 Median:119.00 stddev:4.69 (3.9%) stddev-sans-first:4.87 03:21:28 INFO - PROCESS | 27606 | Values: 118.0 118.0 118.0 116.0 128.0 126.0 117.0 119.0 128.0 119.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.90 Median:155.50 stddev:3.03 (2.0%) stddev-sans-first:3.00 03:21:28 INFO - PROCESS | 27606 | Values: 158.0 154.0 156.0 152.0 154.0 155.0 156.0 161.0 151.0 152.0 03:21:28 INFO - PROCESS | 27606 | 03:21:28 INFO - PROCESS | 27606 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:1.33 (2.2%) stddev-sans-first:1.20 03:21:28 INFO - PROCESS | 27606 | Values: 63.0 63.0 62.0 61.0 61.0 60.0 61.0 60.0 60.0 59.0 03:21:28 INFO - PROCESS | 27606 | -------- Summary: end -------- 03:21:28 INFO - PROCESS | 27606 | 03:21:29 INFO - TEST-INFO | 27606: exit 0 03:21:29 INFO - TEST-OK | kraken | took 60133ms 03:21:29 INFO - SUITE-END | took 1172s 03:21:30 INFO - Completed test suite (00:19:32) 03:21:30 INFO - javascript benchmark 03:21:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3258.7393008559316, 3330.4837750148727, 3332.383509050826, 3350.6481819408946, 3376.274101068271, 5422.132458806587, 5442.319710036246, 5458.235846981426, 5484.862878428041, 5485.588580856437, 5464.180372944061, 5501.862453438656, 5508.14623733321, 5517.2551705519745, 5540.86147846303, 4958.281049247857, 5026.070177017245, 5042.420121685998, 5083.034223497542, 5116.411612664554, 4927.704337739747, 4987.875303117433, 4994.750262486879, 5010.298558201855, 5040.294358789773, 4975.0796102721115, 5016.548510634042, 5084.872878178038, 5089.974550127245, 5114.590832733373, 5009.724465154415, 5041.344625198737, 5078.720670363128, 5083.262926875601, 5097.3118628984985, 4848.781804818792, 4911.607071434277, 4953.0341583391255, 4957.231629097496, 4996.150654388763, 5377.6504527206, 5418.187271909206, 5432.483914028161, 5445.754941027623, 5464.808731694372, 5707.0, 5867.237259156283, 5871.647701137903, 5872.295324561037, 5873.412658734135, 5831.650100993954, 5858.384869588717, 5862.355140934494, 5950.494207992326, 5970.13433052205, 5560.888782224333, 5594.608377413582, 5596.636218645775, 5597.384287728347, 5599.6640201587625, 5818.127280907897, 5962.672053037038, 6025.78909738157, 6066.848328791815, 6078.422549857756, 5787.536997040227, 5836.445537673914, 5843.211166492554, 5883.558733095035, 5884.764609415575, 5932.911006334908, 5940.940590594038, 5962.1056841473255, 5967.075103358986, 5969.224000879858, 5644.520215781622, 5794.82615521535, 5852.853678658068, 5913.822585322448, 5923.348431672513, 5906.7637294508595, 5910.497607703393, 5912.970435147883, 5917.585768996215, 5932.436418540232, 4530.456345238593, 4572.657050721209, 4574.222382195035, 4576.702514336557, 4598.425196850394, 4001.519817621846, 4009.67922566194, 4055.1078762672164, 4064.878053658395, 4065.8170382332864, 5591.608587398924, 5599.804006859822, 5608.046632072557, 5611.242482264924, 5617.101263797855, 5891.0, 5893.970530147408, 5898.705064746746, 5899.76400943966, 5935.673537955454, 5394.703291319015, 5503.477169668875, 5554.166874968706, 5564.221009058735, 5596.132599447011, 5043.697378157322, 5043.773030213649, 5044.924326135111, 5053.69677819332, 5075.695458272442, 6017.157597936292, 6078.817635470943, 6125.509959203253, 6138.202033735675, 6138.202033735675, 5808.7095645218415, 5838.678872661874, 5868.706564671664, 5926.436988486087, 5947.762089516371, 6179.227596550431, 6189.84525386869, 6192.473639740492, 6196.287426945959, 6200.534959877937, 5684.971575142098, 5713.428657134254, 5818.272715910512, 5826.184334193301, 5833.241678581757, 5808.738606762621, 5915.763369465173, 5919.881602367843, 5925.762969481172, 5957.046872500379, 5747.19539264495, 5751.913721294268, 5753.798617048383, 5755.65466072037, 5765.048766953573, 5603.74783134768, 5686.829395118153, 5737.942620573908, 5739.598228123991, 5758.193852860519, 5694.402087780723, 5717.514011308919, 5727.570432217517, 5728.3985181557, 5752.9137062943255, 5808.215890854681, 5824.825255242349, 5849.883002339844, 5852.239208902815, 5857.384974577608, 5523.917141242965, 5558.388577256579, 5618.438156184349, 5664.235328230801, 5694.658320500783], "value": 5441.418508252917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.5167607533597, 596.7642781101465, 600.6036016229286, 641.3041849593191, 656.1830521001355, 32980.0, 33406.66593334066, 45317.999999999956, 46955.53044469554, 46971.76514117433, 48674.0, 49131.50868491314, 49164.75417622916, 49204.50795492044, 49261.0, 9052.411593246434, 9191.35660503765, 9198.172164505193, 9203.723888283363, 9206.907930920672, 9002.684906028291, 9074.546272686372, 9112.544372781334, 9120.087991200893, 9125.452472851617, 9158.587863546123, 9165.266778657691, 9186.678466253683, 9189.448633082004, 9192.540372981324, 9000.59497322622, 9089.27285817133, 9106.089391060907, 9135.36052476327, 9138.406003609778, 9018.684346047923, 9071.818563628756, 9077.0, 9079.04670009646, 9089.227415669677, 45459.31811022837, 45973.77013114913, 46037.38869139564, 46049.76975115136, 46119.0, 3577.338192434408, 3777.660010599032, 3780.4707340972286, 3788.6211378861894, 3880.47613572167, 34635.307293853984, 34761.82619086889, 35191.4721279181, 36036.63963360359, 36594.8170259147, 4475.619572336355, 4482.731036137842, 4513.27787553993, 4629.768511574408, 4706.952930470686, 366731.0, 367807.0, 371290.0, 371496.1425192884, 372404.0, 38128.0, 39174.80412597976, 39255.0, 67593.66203169052, 67997.0, 40983.38524922128, 41017.589824101975, 41044.58955410468, 41125.177496449905, 41181.0, 17487.300507979537, 17758.0, 17782.0, 17785.28858845632, 17787.911060444872, 14405.495807646683, 14599.0, 14615.415383384758, 14619.92690036543, 14628.926855365655, 9063.909360906306, 9206.125418085272, 9213.677521286723, 9262.212711919563, 9285.953570232106, 3424.417848965632, 3434.0899661589697, 3513.5608048993877, 3527.876524321636, 3532.9293414131575, 1039.6984874386494, 1117.2179474367813, 1196.85637723472, 1201.3392634051236, 1254.8368712067372, 33285.16787080342, 34915.82542087324, 35077.29845403129, 35187.64812351895, 35525.111872202884, 1142.2917790969486, 1165.9475323610288, 1179.9115066370057, 1185.2177562808506, 1262.5202423079172, 18270.908645456686, 18308.685690481034, 18348.266069357076, 18474.168662410222, 18551.44345669632, 154170.0, 573589.0, 575164.0, 577119.0, 579284.0, 62871.37128628655, 63260.683696581225, 63737.0, 63806.36193638097, 63934.36065639377, 1258.8363512743285, 1355.3765267976842, 1370.2121280263889, 1427.7715565509468, 1461.590754588717, 33694.66305337013, 34583.4812477808, 35241.0, 35595.32934489359, 35884.461733074546, 54244.0, 59353.406465934786, 60830.39169608247, 60937.39062609317, 66939.0, 537827.0, 538025.0, 539988.0, 540016.0, 542141.0, 38917.80541097277, 40427.59572404238, 45809.77095114503, 46972.0, 47451.28823067726, 53777.73111134419, 54034.729826350616, 54339.72830135824, 54432.455675444326, 54776.0, 43769.0, 44598.108037838414, 44894.0, 45382.31926521171, 45470.54529454663, 43579.34630980474, 43743.34384984292, 44567.55432445634, 45076.549234507234, 45737.54262457466, 43358.78320608377, 43929.56070439255, 44203.33694994514, 44580.33129503125, 45387.77306113448, 37891.81054094712, 38446.42330365103, 38546.6145338543, 38830.223395531364, 38920.6107938917, 28711.0, 28884.133475995754, 29085.23690249423, 29591.704082959757, 29652.406951861274], "value": 20409.675961076413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.1137389915924, 433.9587739164778, 434.16856719382383, 435.4984850108632, 443.7315424168376, 18282.90858545707, 18557.0, 19134.0, 19731.309404170857, 19747.407577772654, 18263.0, 18703.625927481444, 18806.999999999967, 19095.14071866766, 19117.617647647072, 5738.713064346787, 6008.068749343859, 6024.246969128859, 6027.126066720323, 6032.396760323976, 4301.204277208726, 4996.125678006352, 5893.705314734268, 5899.233099697055, 6584.407403333699, 6597.769078082268, 6701.664916754167, 6703.095082163894, 6710.798676039727, 6742.0, 6486.221653401575, 6646.1027761252335, 6709.899351509707, 6711.026901099338, 6723.596584204964, 4039.414284928669, 5437.401885792559, 5761.827145185651, 5903.911441328383, 5911.527077833763, 23979.40051498709, 24033.0, 24035.519289614283, 24207.03171873123, 24289.87855060731, 2275.6927814744963, 2357.3399448154546, 2406.2781165650235, 2626.500964816679, 3152.621685397767, 10403.531841067095, 10575.0, 10890.074343680797, 10967.616133435291, 14085.436582536686, 2034.918603255868, 2081.8542702010864, 2096.8322534197227, 2116.1323857218467, 2158.32012915931, 80393.0, 81279.59360203161, 82927.0, 82936.0, 83152.58423707842, 11639.06887449002, 11731.47208375625, 11789.233699809485, 11815.468303926344, 11817.940910295394, 16627.334906603843, 16775.496735097968, 16788.412405565745, 16800.579985500215, 16803.0, 8661.393702440895, 8702.129787021247, 8958.238549723215, 8992.8651070234, 9107.99436186057, 8667.696630617898, 8721.433106848035, 8751.824963500694, 8835.911640883638, 8838.55807209637, 4952.8514144575, 5067.315912351785, 5100.591952643779, 5118.0, 5129.12804823181, 2045.059272734529, 2077.6883467479997, 2091.979080209209, 2115.4394085567337, 2135.316698656446, 973.7273563402117, 1037.64719995202, 1105.4638500327335, 1138.9715257118473, 1146.2033886448837, 19202.0, 19427.32004379868, 19660.21359145618, 19742.210311587663, 19857.702134467996, 1023.150784848574, 1080.8378743188584, 1086.6957251969461, 1095.638439315024, 1138.6413279816832, 10826.133909287395, 10985.945070274598, 11007.449627518592, 11073.169512286611, 11177.83233251502, 432013.0, 439769.0, 455968.0, 457032.0, 457069.0, 91915.5404222988, 93952.0, 94876.0, 95796.52101739447, 98245.0, 1004.6282875336173, 1010.802893435773, 1026.2764750850633, 1041.3491567770143, 1048.580567772897, 17326.740098898274, 17396.39112631052, 17560.91219543894, 17651.911740441217, 17895.552611184823, 40363.3945490812, 40463.595364045985, 41039.794801025804, 41572.79213603913, 42673.573264268205, 53866.0, 58240.70879645574, 58791.4120858786, 58840.411595883495, 59212.407875922414, 8614.095519970564, 8622.094680058504, 8674.349423793366, 8790.824183516423, 8843.425177363451, 9340.206082483064, 12515.74968500643, 12832.55086071983, 12869.485220591072, 12873.485060597472, 11141.164412669244, 11288.774224515299, 11301.208915375844, 11532.135089868125, 11907.52369905194, 11443.485043173243, 11447.484863181011, 11450.427478626201, 11506.827397588875, 11747.353895535827, 11472.852714728795, 11740.295582265091, 11804.0, 12063.879361206276, 12112.636620901387, 8496.660133594834, 8692.52191129494, 8712.738617841474, 8785.121487851164, 8793.956030219808, 11152.609658661908, 11226.943865280948, 11507.597234096766, 11720.355380454157, 11732.061435085343], "value": 9544.293966069592, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.4631874090996, 1396.3646540823922, 1412.6327154939713, 1479.4156308258232, 1481.8592233737795, 9027.819443611124, 9399.483028433433, 9404.10660987206, 9582.089701478348, 9586.424814511134, 9362.391444556099, 9561.521923903794, 9564.282678799083, 9565.330426870121, 9567.904320956788, 2499.1752721601874, 2523.621456781488, 2524.936876578082, 2525.9621105683427, 2527.431327951215, 2489.103922587867, 2528.089887640448, 2528.1657053172503, 2532.316274605855, 2541.59334506479, 2403.7476065013184, 2432.3554258121612, 2486.316263027664, 2487.465194983078, 2489.265666628349, 2364.255259593231, 2372.9050837966547, 2382.3805810489225, 2446.7186273578586, 2453.6810214671978, 2366.3255972048055, 2390.725066617344, 2396.628522579003, 2396.772306630867, 2403.375122468153, 8803.955980220058, 8978.50618215998, 9358.344915855892, 9383.343165978384, 9403.29475289356, 5455.8363249102595, 5580.441955804427, 5581.441855814427, 5583.246261754652, 5595.860103497404, 3700.648438398348, 3730.682891954187, 3798.3352913240074, 3803.2583646189005, 3832.4442955771256, 1037.8806437259736, 1163.6974386659444, 1189.328029663246, 1190.3691043746828, 1195.842974547196, 13240.735185296337, 13318.600441986659, 13322.733545329136, 13455.0, 13476.865231347758, 4752.5485078917445, 4809.0, 4927.039227350704, 4932.580730637864, 4946.678465899777, 10640.787184256425, 10642.627508037182, 10652.786944261226, 10680.732981675521, 10696.465176741132, 5536.363318218457, 5543.805966791143, 5544.667319960815, 5550.861228469321, 5555.944440555542, 4339.696221264481, 4718.834840780556, 4860.538248866352, 4869.196582563837, 4974.801007959641, 3955.7033222508426, 4058.898527536835, 4064.1262128642484, 4140.426637877647, 4187.916241675211, 1184.774892770388, 1194.2774621353956, 1195.1633856300625, 1200.609801814414, 1202.0, 820.3754680951341, 899.6266549381933, 970.825251454745, 978.4080631218011, 994.4679596415883, 5865.73604187804, 6130.264368275835, 6154.661493617805, 6172.506199504029, 6261.311255761862, 442.6591524526097, 632.9746810127543, 1072.3351522056219, 1084.967450976472, 1116.7989761842946, 3989.9800500997308, 4002.8198731057173, 4055.3105971984833, 4058.6347228748964, 4063.4514340563646, 11934.820977685167, 12131.090168237417, 12256.20334678261, 12274.32491212974, 12303.384830758427, 12058.276503409823, 12068.336241506626, 12076.577319793942, 12083.697907552205, 12099.697507562381, 8593.570321483901, 8824.294056475466, 8839.46963182211, 8982.562789953576, 8987.41581797181, 6086.847828804315, 6130.509559235161, 6139.815805525842, 6166.845828854314, 6274.874502510015, 10723.142148628247, 11147.72130696739, 11219.831702524305, 11240.999999999673, 11249.100071994384, 11122.0, 11286.379249141048, 11308.264962777723, 11324.37715925632, 11424.257423267794, 6942.548734332252, 7151.141862976476, 7649.923500765144, 8036.116027237116, 8209.302209312274, 9205.493697846683, 9220.907790922005, 9306.813863722824, 9310.0, 9316.767080823034, 8091.312238459562, 8262.132476089924, 8277.379196560403, 8378.49729016261, 8386.412951093365, 7829.960850195713, 8058.677652893819, 8133.877991830245, 8182.7545173644885, 8234.176582341717, 7147.178074521288, 7211.42308615299, 7265.782026539212, 7460.253974602496, 7479.214682458263, 5257.132573125392, 5338.878835444445, 5451.836444906659, 5453.209284653623, 5455.781768729206, 5129.2306154075695, 5322.175062865107, 5422.0, 5428.6199966002005, 5480.205370221216], "value": 4841.765396759797, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.4195987800678, 343.833240878174, 346.8179205916895, 351.04865813645023, 351.32545512615775, 5337.813176538822, 5347.91978120328, 5351.705656188903, 5365.704886231256, 5366.356037275523, 5296.576273898088, 5299.470052994699, 5303.814366497173, 5306.0, 5352.652077614952, 4016.0963783148777, 4087.9182416351655, 4200.453940987667, 4219.3881887126345, 4270.850520231793, 4157.230912281222, 4180.770057646828, 4184.456020717303, 4223.51429585597, 4265.936010959838, 4000.6199411055904, 4130.772807495587, 4172.666186705056, 4183.309753890604, 4186.832526698929, 4094.1985526368453, 4160.396742472355, 4171.20747058058, 4194.3498757692605, 4195.895102622459, 3694.9445758313645, 3698.779402797097, 3699.112213068871, 3722.236941427001, 3731.9253614927547, 5065.924011139836, 5146.382434107931, 5146.485351464861, 5146.9485305146845, 5152.124138896396, 4520.819167233307, 4711.892705214272, 4724.582625212457, 4740.597049250818, 4780.235162374003, 5588.273524441837, 5596.692181929992, 5598.356189038272, 5601.271834661508, 5604.635698679573, 4018.9196216075807, 4032.536258330271, 4032.983508245877, 4075.572064933169, 4095.877123686294, 5584.972075139639, 5755.827325180251, 5809.767609295665, 5815.772246403202, 5821.27234095738, 4103.938440923389, 4127.133302006612, 4132.483439570054, 4133.4626498555, 4147.875563733093, 4188.214837133272, 4207.590457196873, 4211.410402543646, 4231.978840105779, 4234.745915245096, 4550.840720574764, 4761.666683332202, 4783.689060211075, 4810.306228512044, 4825.0, 5533.505953392561, 5540.584456165804, 5574.721263936868, 5578.684845788952, 5613.242212301368, 1473.668424604477, 1478.578605097542, 1483.592012196633, 1484.7401704701633, 1486.8959172857797, 979.3976704326788, 987.2398253344884, 996.1283876607968, 998.201438848918, 999.6751055906714, 5408.45915408456, 5422.43064478232, 5438.945610543923, 5444.40111587725, 5510.6969116698165, 5717.4854263115685, 5771.336296325976, 5785.88428231433, 5788.944924551808, 5827.24245848037, 5275.695718044819, 5323.893522129536, 5423.2136340230445, 5437.34751829783, 5460.96241714073, 3034.8786048558254, 3037.817730936151, 3043.4934707320017, 3053.7937514681576, 3054.1448394449144, 5719.48524632785, 5880.441358071062, 5943.167956486095, 5963.5527335450015, 6000.729967151488, 5730.914036289376, 5754.338251101177, 5762.510186634183, 5776.3068431787615, 5797.449242321973, 5844.415558444121, 5943.138244954457, 5961.821145365646, 5962.0460726284455, 6001.159837622737, 5474.507294343528, 5737.512311453406, 5776.111944027986, 5785.56608254391, 5787.334456537384, 5609.186667933045, 5623.409541998031, 5646.632968857011, 5692.601517893708, 5706.861226325422, 5829.417058294137, 5989.790357337472, 6011.128386384037, 6089.847753806191, 6106.877862442815, 4059.5534491206536, 4072.9389059163545, 4088.4889388826396, 4088.918221635491, 4089.8752842967233, 5562.582806289463, 5631.2960879890015, 5721.7425215866215, 5781.190633311424, 5842.941570584239, 5617.466340697627, 5621.4659607337235, 5639.210510528447, 5646.435356464321, 5669.31968163823, 5635.492805647511, 5664.688442135557, 5675.460831221028, 5677.602567820378, 5682.659040457585, 5257.4216836147225, 5417.589172818216, 5579.442055794388, 5586.217929489956, 5587.0388693992245, 5668.03643380618, 5683.8010669626365, 5685.800996965087, 5686.77252909879, 5699.943000569941, 5530.889382212253, 5546.4453554644215, 5606.803761868315, 5616.803411880728, 5659.434056594308], "value": 4295.964246361253, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.1576510660399, 757.2389748302954, 761.7181642792168, 787.8384931089128, 792.1167897793957, 15807.604809879758, 16571.83428165718, 16599.50201493957, 16618.418355357564, 16640.251188696493, 14472.421103155888, 14901.478448254285, 14913.105213687186, 15254.466093686724, 15327.69344613107, 720.7837648705394, 835.3734698975768, 861.9698310559131, 869.6738722978882, 876.1720174435161, 874.422880898607, 875.763543843162, 876.0494863073573, 877.1842186766305, 878.5782824244367, 915.7619019055061, 916.7891384981458, 917.7980844214262, 917.9082091790801, 917.9403338782957, 594.7977687586241, 598.0700011482144, 604.3563604760916, 753.0473950452655, 906.5875026862761, 437.34835095707484, 438.3753151758731, 439.14147840970827, 440.6871121503736, 440.73555866479853, 15650.921745391313, 15659.21703914811, 15667.451639192574, 15668.0, 15668.294926728211, 14471.204083775388, 15032.699346013127, 15074.698506029928, 15105.093694378373, 15238.390464381411, 12720.109592328541, 12894.548690795871, 13121.278329691862, 13376.665583360396, 14033.929830350782, 4401.867943961686, 4628.953710462886, 4697.718136911796, 4720.575148236675, 4776.785044672998, 78048.21951780467, 78226.6088669553, 78865.0, 79221.60389198016, 79440.0, 10749.032587067202, 10790.136789056856, 10880.455977201109, 11892.124014440818, 12329.876701232874, 21889.67165492519, 22183.889080554818, 22279.0, 22287.0, 22287.219947301768, 8404.285635721033, 8576.914230857736, 8656.567171641393, 8737.388382813142, 8828.161324674145, 4769.332293478916, 4969.204927211629, 4981.775820088104, 5100.336956195745, 5352.036633405946, 3832.674222691047, 3929.548101968253, 3932.4073750131242, 3937.9606203938165, 3939.487866577384, 627.6046090962665, 637.346719612401, 645.5481163185789, 650.7982525417136, 661.7253839656585, 716.30160593421, 753.5102183580607, 775.2634996753108, 777.7355699062346, 809.3323008517996, 10325.380477171244, 10670.946645266878, 10707.678769636923, 10846.566137354417, 10896.455177241265, 648.6918713610997, 674.3088334457123, 706.3960313931515, 764.6635480388611, 770.3221165374547, 4966.875828104254, 5158.123119069767, 5189.429162792166, 5282.867928301823, 5343.62594618373, 119684.0, 119868.0, 119913.40043299728, 120433.0, 120934.0, 74370.0, 78625.60687196642, 101471.0, 105029.0, 105645.47177264064, 67615.66192169007, 91785.54107229422, 92307.0, 92921.53539232396, 93216.53391732997, 10086.0, 10376.688699339033, 10392.37645741258, 10393.740156496147, 10465.47672616351, 53950.19074713961, 54559.45440545703, 55539.44460555504, 55667.0, 56944.0, 67156.6642166786, 67829.66085169543, 68064.0, 69551.65224173847, 71098.28901711124, 13391.0, 13795.517156899461, 13911.165330080228, 14123.929380353033, 14272.785908211177, 15020.324085415963, 15519.146446945075, 15551.455699050479, 16489.587760306087, 16842.1578921051, 14494.492692755703, 14598.467160947948, 14749.631259218606, 14766.778498322748, 16112.516624501284, 14830.555083347517, 14925.253737312874, 15259.694806103595, 15440.691186176438, 15499.14754688503, 14932.552023439313, 14945.925270373578, 14987.850121498646, 15133.167675777939, 15220.847791522387, 12295.385230738606, 12340.81488777651, 12687.936560317139, 12864.935675321563, 13023.934880325538, 12455.252684838939, 12580.937095314464, 12806.103572750191, 12887.226766394046, 12953.676158096123], "value": 7860.858192007955, "unit": "score"}], "name": "dromaeo_css", "value": 7466.980373000903, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 119.0, 110.0, 111.0, 111.0, 113.0, 110.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 129.0, 173.0, 125.0, 130.0, 124.0, 147.0, 177.0, 130.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 166.0, 160.0, 162.0, 161.0, 161.0, 161.0, 160.0, 159.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 116.0, 76.0, 76.0, 75.0, 75.0, 104.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 115.0, 84.0, 84.0, 84.0, 118.0, 85.0, 84.0, 123.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 148.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 51.0, 52.0, 50.0, 52.0, 51.0, 51.0, 52.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 60.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 77.0, 76.0, 77.0, 78.0, 84.0, 77.0, 117.0, 78.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 116.0, 128.0, 126.0, 117.0, 119.0, 128.0, 119.0], "value": 120.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 156.0, 152.0, 154.0, 155.0, 156.0, 161.0, 151.0, 152.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 59.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1478.8000000000002, "alertThreshold": 2.0}]} 03:21:30 INFO - Return code: 0 03:21:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:30 ERROR - # TBPL SUCCESS # 03:21:30 INFO - Running post-action listener: _resource_record_post_action 03:21:30 INFO - ##### 03:21:30 INFO - ##### Finished run-tests step (success) 03:21:30 INFO - ##### 03:21:30 INFO - Running post-run listener: _resource_record_post_run 03:21:30 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 16384; Write bytes: 517386240; Read time: 296; Write time: 449212 03:21:30 INFO - TinderboxPrint: CPU usage
13.0% 03:21:30 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 296 03:21:30 INFO - TinderboxPrint: I/O write bytes / time
517,386,240 / 449,212 03:21:30 INFO - TinderboxPrint: CPU idle
8,259.0 (86.9%) 03:21:30 INFO - TinderboxPrint: CPU user
1,195.0 (12.6%) 03:21:30 INFO - TinderboxPrint: Swap in / out
0 / 0 03:21:30 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 252547072; Read time: 72; Write time: 266208 03:21:30 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 12288; Write bytes: 252854272; Read time: 224; Write time: 181052 03:21:30 INFO - Running post-run listener: _upload_blobber_files 03:21:30 INFO - Blob upload gear active. 03:21:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:21:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:21:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:35 INFO - (blobuploader) - INFO - Done attempting. 03:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:36 INFO - (blobuploader) - INFO - Done attempting. 03:21:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:37 INFO - (blobuploader) - INFO - Done attempting. 03:21:37 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:37 INFO - Return code: 0 03:21:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eca03fa646a2377967509da8edc65f29fbf162911873aa1815b3333b4ad5e505b184389d3658666b9c03c5e8a6279c188d5a0ff794392ebf53b5983a3c0fac36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ace15f1553fc5d9c84053a4753d002272ee538e7f0c28a1c84744142c8379a77a49c6a19eaf56f740a307b8c514c66f215022240b19b925bb72e3c255f8bc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420a8c1f6c2eb19a3c9f26c86cb9f95313418f36456135aa7b425f8f9f0701fc4cba95dc7d23ab8a635d4b4404693f8360ff2e2b8afeab3f4bb5fe4baca66717"} 03:21:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:37 INFO - Contents: 03:21:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eca03fa646a2377967509da8edc65f29fbf162911873aa1815b3333b4ad5e505b184389d3658666b9c03c5e8a6279c188d5a0ff794392ebf53b5983a3c0fac36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ace15f1553fc5d9c84053a4753d002272ee538e7f0c28a1c84744142c8379a77a49c6a19eaf56f740a307b8c514c66f215022240b19b925bb72e3c255f8bc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420a8c1f6c2eb19a3c9f26c86cb9f95313418f36456135aa7b425f8f9f0701fc4cba95dc7d23ab8a635d4b4404693f8360ff2e2b8afeab3f4bb5fe4baca66717"} 03:21:37 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:37 INFO - Copying logs to upload dir... 03:21:37 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.628513 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-30 03:21:37.272133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:37.277600) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038 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/eca03fa646a2377967509da8edc65f29fbf162911873aa1815b3333b4ad5e505b184389d3658666b9c03c5e8a6279c188d5a0ff794392ebf53b5983a3c0fac36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ace15f1553fc5d9c84053a4753d002272ee538e7f0c28a1c84744142c8379a77a49c6a19eaf56f740a307b8c514c66f215022240b19b925bb72e3c255f8bc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420a8c1f6c2eb19a3c9f26c86cb9f95313418f36456135aa7b425f8f9f0701fc4cba95dc7d23ab8a635d4b4404693f8360ff2e2b8afeab3f4bb5fe4baca66717"} build_url:https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011363 build_url: 'https://queue.taskcluster.net/v1/task/f1OtShs4TkuCRKU9u8J4NA/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/eca03fa646a2377967509da8edc65f29fbf162911873aa1815b3333b4ad5e505b184389d3658666b9c03c5e8a6279c188d5a0ff794392ebf53b5983a3c0fac36", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ace15f1553fc5d9c84053a4753d002272ee538e7f0c28a1c84744142c8379a77a49c6a19eaf56f740a307b8c514c66f215022240b19b925bb72e3c255f8bc9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420a8c1f6c2eb19a3c9f26c86cb9f95313418f36456135aa7b425f8f9f0701fc4cba95dc7d23ab8a635d4b4404693f8360ff2e2b8afeab3f4bb5fe4baca66717"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:37.308536) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:37.308847) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469862317.740251-446686038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:37.392905) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:21:37.395239) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:21:37.395554) ========= ========= Total master_lag: 0.34 =========