builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1469872860.44 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.437937) ========= 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.438389) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.438676) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.517456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.517720) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 03:01:01-- 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% 19.9M=0.001s 2016-07-30 03:01:01 (19.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093322 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.713673) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.713982) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017781 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.778633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:00.779046) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 03:01:01,608 truncating revision to first 12 chars 2016-07-30 03:01:01,608 Setting DEBUG logging. 2016-07-30 03:01:01,608 attempt 1/10 2016-07-30 03:01:01,608 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,906 unpacking tar archive at: mozilla-aurora-8847dd9e9a2d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630910 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.438994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.442357) ========= script_repo_revision: 8847dd9e9a2da7a4ad074ab97e085295e50746c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.442795) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.443085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:01:01.453535) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-30 03:01:01.454040) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716 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-006', 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": 394, 03:01:02 INFO - "slavename": "talos-linux64-ix-006", 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:05 INFO - Downloaded 1361 bytes. 03:01:05 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:01:05 INFO - Using the following test package requirements: 03:01:05 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:01:05 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:01:05 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:01:05 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'jsshell-linux-x86_64.zip'], 03:01:05 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:01:05 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:01:05 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:01:05 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:01:05 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:01:05 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:01:05 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:01:05 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:01:05 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:05 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:05 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:05 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:05 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:05 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:06 INFO - Downloaded 24495819 bytes. 03:01:06 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:06 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:15 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:17 INFO - Installing pip.................done. 03:01:17 INFO - Return code: 0 03:01:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:17 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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:17 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:17 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:17 INFO - Using env: {'DISPLAY': ':0', 03:01:17 INFO - 'HOME': '/home/cltbld', 03:01:17 INFO - 'LANG': 'en_US.UTF-8', 03:01:17 INFO - 'LANGUAGE': 'en_US:en', 03:01:17 INFO - 'LOGNAME': 'cltbld', 03:01:17 INFO - 'MAIL': '/var/mail/cltbld', 03:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:17 INFO - 'MOZ_NO_REMOTE': '1', 03:01:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:17 INFO - 'NO_EM_RESTART': '1', 03:01:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:17 INFO - 'PWD': '/builds/slave/test', 03:01:17 INFO - 'SHELL': '/bin/bash', 03:01:17 INFO - 'SHLVL': '1', 03:01:17 INFO - 'TERM': 'linux', 03:01:17 INFO - 'TMOUT': '86400', 03:01:17 INFO - 'USER': 'cltbld', 03:01:17 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 03:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:17 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 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 - Successfully installed psutil 03:01:20 INFO - Cleaning up... 03:01:20 INFO - Return code: 0 03:01:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:20 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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:20 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:20 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:20 INFO - Using env: {'DISPLAY': ':0', 03:01:20 INFO - 'HOME': '/home/cltbld', 03:01:20 INFO - 'LANG': 'en_US.UTF-8', 03:01:20 INFO - 'LANGUAGE': 'en_US:en', 03:01:20 INFO - 'LOGNAME': 'cltbld', 03:01:20 INFO - 'MAIL': '/var/mail/cltbld', 03:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:20 INFO - 'MOZ_NO_REMOTE': '1', 03:01:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:20 INFO - 'NO_EM_RESTART': '1', 03:01:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:20 INFO - 'PWD': '/builds/slave/test', 03:01:20 INFO - 'SHELL': '/bin/bash', 03:01:20 INFO - 'SHLVL': '1', 03:01:20 INFO - 'TERM': 'linux', 03:01:20 INFO - 'TMOUT': '86400', 03:01:20 INFO - 'USER': 'cltbld', 03:01:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 03:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:20 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:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:22 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:22 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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:25 INFO - Return code: 0 03:01:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:25 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:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:25 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:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:25 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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:25 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:25 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:25 INFO - Using env: {'DISPLAY': ':0', 03:01:25 INFO - 'HOME': '/home/cltbld', 03:01:25 INFO - 'LANG': 'en_US.UTF-8', 03:01:25 INFO - 'LANGUAGE': 'en_US:en', 03:01:25 INFO - 'LOGNAME': 'cltbld', 03:01:25 INFO - 'MAIL': '/var/mail/cltbld', 03:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:25 INFO - 'MOZ_NO_REMOTE': '1', 03:01:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:25 INFO - 'NO_EM_RESTART': '1', 03:01:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:25 INFO - 'PWD': '/builds/slave/test', 03:01:25 INFO - 'SHELL': '/bin/bash', 03:01:25 INFO - 'SHLVL': '1', 03:01:25 INFO - 'TERM': 'linux', 03:01:25 INFO - 'TMOUT': '86400', 03:01:25 INFO - 'USER': 'cltbld', 03:01:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 03:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:25 INFO - '_': '/tools/buildbot/bin/python'} 03:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:25 INFO - Downloading/unpacking blobuploader==1.2.4 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:27 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:28 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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-AUsKGd-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-3fKJjD-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-HmOzDW-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-PUS9T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:29 INFO - Running setup.py (path:/tmp/pip-TdzxkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:29 INFO - Running setup.py (path:/tmp/pip-xBJB9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:29 INFO - Running setup.py (path:/tmp/pip-oW6TMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:29 INFO - Running setup.py (path:/tmp/pip-BQ7AeG-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-JlMAm9-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-vxxjY5-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-uhNHSq-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-0WGHHd-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-L5YczT-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-_yXrxU-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-ckRFVM-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-MoXdFh-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-TUZZk6-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:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:30 INFO - Running setup.py install for mozcrash 03:01:30 INFO - Running setup.py install for mozdebug 03:01:30 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:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Running setup.py install for mozprocess 03:01:31 INFO - Running setup.py install for mozprofile 03:01:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:31 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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-f68ts8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:32 INFO - Running setup.py (path:/tmp/pip-SpkAVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:32 INFO - Running setup.py (path:/tmp/pip-K5QUBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:32 INFO - Running setup.py (path:/tmp/pip-S4AYFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:32 INFO - Running setup.py (path:/tmp/pip-SOxsK5-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-eXVJfD-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-z3_Sze-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-H1XYBj-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-pDDx7k-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-P2hpiA-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-X6YTqz-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-WHkde7-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-zjMpF1-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-WhvYLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:33 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:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:33 INFO - Running setup.py (path:/tmp/pip-MQ10zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:33 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:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:33 INFO - Running setup.py (path:/tmp/pip-xOiWV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:33 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:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:33 INFO - Running setup.py (path:/tmp/pip-oHblr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:33 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:33 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:33 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:33 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:33 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:33 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:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:33 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:33 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:33 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:33 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:33 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:33 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:35 INFO - Downloading blessings-1.6.tar.gz 03:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:35 INFO - Installing collected packages: blessings 03:01:35 INFO - Running setup.py install for blessings 03:01:35 INFO - Successfully installed blessings 03:01:35 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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): jsonschema in ./venv/lib/python2.7/site-packages 03:01:36 INFO - Cleaning up... 03:01:36 INFO - Return code: 0 03:01:36 INFO - Installing flake8 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f511fbaef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b10350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', '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', 'flake8'] 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 flake8 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': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 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 - Downloading/unpacking flake8 03:01:36 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:36 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 - 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:36 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:36 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:36 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:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:01:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:01:38 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:38 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:38 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:38 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:38 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:38 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:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:01:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:01:38 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:38 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:38 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:38 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:38 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:38 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:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:01:39 INFO - Cleaning up... 03:01:39 INFO - Return code: 0 03:01:39 INFO - Running post-action listener: _resource_record_post_action 03:01:39 INFO - Running post-action listener: _start_resource_monitoring 03:01:39 INFO - Starting resource monitoring. 03:01:39 INFO - ##### 03:01:39 INFO - ##### Finished create-virtualenv step (success) 03:01:39 INFO - ##### 03:01:39 INFO - ##### 03:01:39 INFO - ##### Running install step. 03:01:39 INFO - ##### 03:01:39 INFO - Running pre-action listener: _resource_record_pre_action 03:01:39 INFO - Running main action method: install 03:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:39 INFO - Reading from file tmpfile_stdout 03:01:39 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:39 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:55 INFO - Reading from file tmpfile_stdout 03:01:55 INFO - Output received: 03:01:55 INFO - /builds/slave/test/build/application/firefox/firefox 03:01:55 INFO - Running post-action listener: _resource_record_post_action 03:01:55 INFO - ##### 03:01:55 INFO - ##### Finished install step (success) 03:01:55 INFO - ##### 03:01:55 INFO - ##### 03:01:55 INFO - ##### Running run-tests step. 03:01:55 INFO - ##### 03:01:55 INFO - Running pre-action listener: _resource_record_pre_action 03:01:55 INFO - Running main action method: run_tests 03:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:55 INFO - Python 2.7.3 03:01:55 INFO - Return code: 0 03:01:55 INFO - grabbing minidump binary from tooltool 03:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:55 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 0x1b9f1c0>, '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 0x1ba00c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ba0550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01:55 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:55 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:55 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:56 INFO - Return code: 0 03:01:56 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-006', '--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:56 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-006 --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:56 INFO - Using env: {'DISPLAY': ':0', 03:01:56 INFO - 'HOME': '/home/cltbld', 03:01:56 INFO - 'LANG': 'en_US.UTF-8', 03:01:56 INFO - 'LANGUAGE': 'en_US:en', 03:01:56 INFO - 'LOGNAME': 'cltbld', 03:01:56 INFO - 'MAIL': '/var/mail/cltbld', 03:01:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:56 INFO - 'MOZ_NO_REMOTE': '1', 03:01:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:56 INFO - 'NO_EM_RESTART': '1', 03:01:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:56 INFO - 'PWD': '/builds/slave/test', 03:01:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:56 INFO - 'SHELL': '/bin/bash', 03:01:56 INFO - 'SHLVL': '1', 03:01:56 INFO - 'TERM': 'linux', 03:01:56 INFO - 'TMOUT': '86400', 03:01:56 INFO - 'USER': 'cltbld', 03:01:56 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716', 03:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:56 INFO - '_': '/tools/buildbot/bin/python'} 03:01:56 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-006', '--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:47576' 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 520 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3tCUhK/profile http://localhost:47576/getInfo.html) 03:02:00 INFO - PROCESS | 520 | __metrics Screen width/height:1600/1200 03:02:00 INFO - PROCESS | 520 | colorDepth:24 03:02:00 INFO - PROCESS | 520 | Browser inner width/height: 1024/697 03:02:00 INFO - PROCESS | 520 | __metrics 03:02:01 INFO - PROCESS | 520 | 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:01 ERROR - PROCESS | 520 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:02:02 INFO - TEST-INFO | 520: exit 0 03:02:02 INFO - Browser initialized. 03:02:02 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:02 INFO - TEST-INFO | started process 616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3tCUhK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:14 INFO - PROCESS | 616 | RSS: Main: 145338368 03:02:14 INFO - PROCESS | 616 | 03:03:05 INFO - PROCESS | 616 | 1469872985723 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:13 INFO - PROCESS | 616 | Cycle 1(1): loaded http://localhost:47576/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47576/tests/dromaeo/cssquery-ext.html) 03:05:14 INFO - PROCESS | 616 | RSS: Main: 253845504 03:05:14 INFO - PROCESS | 616 | 03:08:16 INFO - PROCESS | 616 | Cycle 1(1): loaded http://localhost:47576/tests/dromaeo/cssquery-ext.html (next: http://localhost:47576/tests/dromaeo/cssquery-jquery.html) 03:08:17 INFO - PROCESS | 616 | RSS: Main: 223793152 03:08:17 INFO - PROCESS | 616 | 03:11:19 INFO - PROCESS | 616 | Cycle 1(1): loaded http://localhost:47576/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47576/tests/dromaeo/cssquery-mootools.html) 03:11:19 INFO - PROCESS | 616 | RSS: Main: 229081088 03:11:19 INFO - PROCESS | 616 | 03:14:21 INFO - PROCESS | 616 | Cycle 1(1): loaded http://localhost:47576/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47576/tests/dromaeo/cssquery-prototype.html) 03:14:22 INFO - PROCESS | 616 | RSS: Main: 225468416 03:14:22 INFO - PROCESS | 616 | 03:17:25 INFO - PROCESS | 616 | Cycle 1(1): loaded http://localhost:47576/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47576/tests/dromaeo/cssquery-yui.html) 03:17:25 INFO - PROCESS | 616 | RSS: Main: 214331392 03:17:25 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | Cycle 1(1): loaded http://localhost:47576/tests/dromaeo/cssquery-yui.html (next: http://localhost:47576/tests/dromaeo/cssquery-dojo.html) 03:20:28 INFO - PROCESS | 616 | RSS: Main: 263176192 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | __start_tp_report 03:20:28 INFO - PROCESS | 616 | _x_x_mozilla_page_load 03:20:28 INFO - PROCESS | 616 | _x_x_mozilla_page_load_details 03:20:28 INFO - PROCESS | 616 | |i|pagename|runs| 03:20:28 INFO - PROCESS | 616 | |0;dojo.html;3305.983470082649;3324.301896601712;3324.5678061851954;3357.429237029705;3361.5461912641786;5374.059539580572;5446.101393270111;5451.427600101992;5461.15352120421;5471;5408.810691625794;5461.590380721452;5467.562594992401;5473.2063850741615;5495.945040549594;4856.7571621418965;4928.211486162215;4949.28235405866;4997.325361076263;5062.468440813716;4810.134175848363;4947.1342515059905;4977.800887964477;4984.327115839352;4988.975055124737;4892.755362231892;4914.287428322891;4965.180745177059;4981.153203955337;4999.575036121916;5053.242013697938;5062.848114556551;5064.3163172971545;5067.5185857343495;5101.7194054327;4791.784369703337;4859.659823812334;4912.238603016538;4976.900461990776;4990.351254336913;5276.023935571893;5278.736063196863;5326.227696983954;5357.303550538444;5360.195970604441;5804.158397032449;5872.119182122673;5874.647521148745;5878.50032747217;5879.8530036749;5652.858678533028;5671.546276297928;5721.4850663440075;5799.623024503394;5922.082077278029;5381.031414345416;5420.810271640513;5431.456854314616;5433.0763770159165;5439.809606663787;5734.139879018097;5904.380040095728;5906.527477801766;5907.438793314628;5908.527317814564;5591.720413979285;5608.803691870765;5640.97179514108;5662.943370566324;5706.857328566819;5762.250907382014;5801.3038435388025;5810.5061069809535;5812.273465816773;5817.0401883689565;5617.8033768817895;5737.942620573825;5746.224259724967;5757.798477053283;5765.1928729977835;5837.941620583825;5861.7655293787775;5864.44287792659;5883;5903.055511118287;4537.705049171794;4539.02410981634;4541.72749635023;4547.886302842389;4557.772111394417;3975.264576053439;3989.5810939851476;4002.8799136025964;4063.045184381668;4090.734102283342;5534.584906132056;5552.056150454352;5558.416366281563;5558.722063896789;5587.357453892773;5833.037548804485;5839.357670656224;5841.211436456109;5843.24037875082;5875.882482350329;5409.918851217235;5480.561555075664;5507.862303442366;5531.917021244684;5535.916961245584;4981.227909673934;4986.8753281168265;5006.0989021975665;5006.724630145377;5013.874653133701;6075.635461872302;6082.118092876508;6084.0265557511475;6095.542834287357;6100.450959413585;5664.235328230801;5801.1878337032;5825.883482330414;5835.44563266506;5863.648181109147;6044.637321760709;6048.032314829607;6064.484518815773;6065.2418447694035;6067.635941843503;5601.131824567198;5693.28833895763;5701.828945131652;5704.429557044263;5705.657660540381;5782.942170578241;5836.795712150054;5862;5916.792912248051;5922.082077278029;5783.624064435799;5786.276715410574;5787.652740835563;5788.27646544182;5791.102379131242;5447.564194864481;5609.467100625434;5739.512141468022;5745.8563535911935;5753.798617048383;5538.0585300499815;5616.354119276172;5618.971905140448;5624.887502250014;5648.209250704823;5674.347450043133;5739.799107031234;5759.884802304015;5767.68277744728;5768.798092066758;5485.61600687964;5511.338639363302;5564.081926482085;5638.182463542846;5663.603547751618 03:20:28 INFO - PROCESS | 616 | |1;ext.html;581.1167026120297;622.1197006236176;636.0903907412398;639.7441023590566;664.4119953840849;29483.41033179335;32167.35665286693;32171.83914080429;47020.529794702044;47916.52083479164;49122;49226.50773492264;49268.26097608539;49354.75322623391;49407;9044.457332560067;9184.678536251233;9193.310501712365;9195.862062069074;9251.25989920808;8117.472364296332;10716.089132423753;10940.617078402258;10949.507272172732;11385.715357116096;11095.167862410311;11171.16216283778;11184.217104802627;11189.664310070712;11194.328340299568;8247.628856701464;8396.790080247982;11100;11202.047825934724;11209.439528023608;10579.523921423555;10941.671749847517;10949.45252737368;10954.780904381947;11349.943250283779;42303.57696423058;42939.35590966138;46587.30119048217;46647.76676116631;46672.5332746675;3806.847726090953;3853.2678791029616;3858.8070596470066;3925.3130702127014;4049.97975010126;34700;35195;35338.469922951175;35789.82105089484;36138.09654758626;4455.509893911667;4478.305862591304;4496.437945256843;4503.684742068057;4507.549245075499;397233;397351;400379;401126;401658;69159.30840691629;69510.65244673846;69871.65064174647;69970;70059.2994070063;39585.80207098946;41580.58419415767;41628.375574365804;43106.35340469895;43376.566234337886;20101.698474522895;20373.286934957207;20447;20455;20473.385798426072;13192.274424906722;13547.932260338832;13690.589282321545;14929.70140597182;15454.613634659;22044.11823527073;22288.66567001496;22352.329430117123;22429.10283588638;22459.43851403728;8047.51714897108;8114.715984940499;8166.591670416456;8186.467879587927;8356.206160414751;1032.9845052324222;1109.3399427340719;1126.6563698072002;1178.4873579992732;1240.7704574653717;33248.83375583107;34262;34456.48315275273;34553.30893382118;34613.65386346104;1037.2946396450336;1052.1744772819152;1054.246213957009;1074.2319241742036;1145.7479354541988;15636.530904072895;17532.474025779247;18269.36057238017;18294.817051829312;18603.627927441645;650753;653061;655510;655602;657699;64177.432902684304;64826.758662066706;64861.67569162124;64985.67507162434;65113.348866511675;1376.4700590272616;1447.6163816588612;1462.2396353895915;1476.4758510728752;1496.6108811709034;34035.48946765751;34321.48517772186;35302;35474.82262588671;35653.465198021535;55137.72431137819;55329.723351382985;55691.44308556862;55708;55780;437972;438294;438777;440496;441303;27021.189364319103;29920;31209;31265;32820.67179328176;52179.47820521746;52971.205431917784;53295.46704532905;54073.18890216571;54237.18644220419;44463.55536444724;45330.54669453263;45526;45760.31359529543;46457.303140453594;44456.77771611121;44961.32558011567;45191.32213016873;45858;46072.308915365626;45253.32120018268;45519.54480455153;45879;46319;46581.76709116433;37499.25001500009;39794.80102599468;40591.1881762369;42464.78767606142;42781.35827962521;27215;27286.727132728418;27294.524311417892;27438.45123097567;27466 03:20:28 INFO - PROCESS | 616 | |2;jquery.html;408.1591920643473;423.26352147263754;430.1161113910913;431.4218946611541;440.7349526481071;18370.540736481576;19177.71233431498;19657.901710491427;19699.5075123122;19952.102155403005;19092.23631054756;19255;19631.509212269666;20085;20142.093605787737;5660.716964151797;5849.181114643953;5882.499987501046;5889.293284805808;5894.4989675877605;1898.0035481372252;1941.2817257614702;4261.680373971949;6007.699615019232;6309.337519560426;6338.587991780542;6347.904781428282;6371.044343348512;6386.042093685961;6390.936090639104;6164.907526387108;6262.499000079983;6312.715927783261;6319.74721011159;6320.683965801692;3882.7087968402484;4079.5920407958965;4251.829926802924;5679.460451257124;5686.0617998030275;21773.673394899088;23233.419164520852;23519.294421167222;23617.76382236173;23621.409464763346;2386.9283921482383;2452.288836237489;2730.426610411816;2840.8863645454157;2867.856607169633;12880.098393112487;13518.188908665512;13689.726205475934;13856.445742170301;14091.647708807259;2026.2604149485478;2037.8165965063067;2046;2047.1299697628597;2069.689546568012;74320.25679743242;74805.62597187034;77504.61247693782;77694.61152694256;78415;10554.947225263824;10605.257631965842;10987.560497580007;11016.944915275373;11132.10943124548;15166.545003649908;15211.61970950713;15216.695666086616;15229.390824367123;15242.3903043877;8118.797030074295;8240.876386854203;8305.95847020761;8366;8410.87383689233;8009.679612815538;8048.356131509582;8049.3962952778775;8115.8782618260775;8131.064927533291;6421.550491465552;6428.389303016206;6519.348065193442;6551.9844424114335;6611.041398997118;1744.5551384396897;1746.6855965926254;1776.209586733891;1784.4289827255154;1882.2282864025683;465.54144167994497;941.9858702119474;1010.9898901010896;1052.273930987616;1058.111186603257;11376;12096.33470159132;12256.509739610494;12314.384280785926;12352.814707779391;465.8586463165259;487.24963556123174;487.97628415462896;492.8078049560674;494.48820470812535;5719.5996280260815;6219.1604133442315;6400.71196796136;6575.4410875076155;6648.501362397839;335909;376506;382764;511862;513348;67146.66426667836;96070;101287;103493.48253258686;105382;1076.3272954403499;1102.6306187427301;1108.6064447121316;1114.1810769084555;1125.5441546173813;17531.912340438725;17552.91223543874;17854;17909.910450447664;18152.27390904402;42028;42487.15025699531;43033.56966430421;43135.13729725325;43710.781446092566;59826;60344;61627;61717.691411542655;61938.38061619326;6940.41006514452;8124;8222.958885205775;8289.875651865106;8451.23938845507;9205.58574864146;9916.950415247878;12245.387730613256;12878.678033048875;13366.732665346834;11232.101431885267;11650.53397864076;11739;11777.705557361134;12085.972692321251;11633;11677.649670509898;11791.528338866348;12071.698207544881;12398.62804115878;11699.590514332298;11727;11925.642230733092;12197.329146897006;12250.75498490043;8597.140285971353;8721.65113395457;8776.605052772513;8865.20213180791;9071.228945539438;10694.251402401756;11720.355380454157;12110.45502952387;12119.515219391125;12199.51201951912 03:20:28 INFO - PROCESS | 616 | |3;mootools.html;1324.7014932817287;1545.4359158906998;1571.7170909236336;1780.928762849486;1878.9906050469754;8247.216514431128;9174.90825091749;9444;9462.905370946288;9692.27307951903;9240.491772952484;9431.764205894857;9437.480938548377;9441.386309889853;9443.197328227092;2158.438805910459;2381.1070848431837;2411.4935863468604;2441.8046556275453;2515.4843257132243;2491.6013437849947;2502.436951685874;2523.785478234343;2527.545041892468;2528.254165021314;2490.3151633300804;2495.800335973118;2497.225859983411;2497.2633073243437;2499.450120973383;2161.0275376080826;2344.507653392781;2406.073661640281;2413.2036427978724;2422.2854257993936;2287.2452090809993;2294.908203671851;2297.5404919016264;2342.7188737351457;2393.7247216570145;9211.447313161165;9267.907320926839;9272.165505104573;9303.860442093373;9375.953120234424;5333.626646134772;5446.673199608036;5452.83641490752;5458.4814442627885;5460.453954604547;3463.9653603463894;3523.982380088083;3618.819059047037;3642.963570364289;3647.8358473868475;1163.97090072748;1172.5485688010178;1173.1142987044811;1183.1776915044047;1187.044429234466;13315.134516256509;13323.600291991255;13346.532871349456;13389.062765606412;13395.933020334836;4918.606511479073;4926.4088309403105;4929.0388374267395;4941.382327209099;5015.423226328946;11094.55621775136;11178.049865761339;11218.607348742755;11244.437778111062;11272.21094523392;4993.0762808879745;5483.177523371526;5503.229547863302;5508.14623733322;5509.47659972302;4913.484084171182;5050.570701490415;5057.570106540912;5074.13739664258;5080.720560369141;4254.914901702011;4257.318828987347;4287.2711639021445;4329.155814616182;4355.847545335898;1293.6377814212035;1342.2080972226433;1344.5428554291589;1345.2264947654942;1346.3268365817091;874.4622057434632;945.7588314979636;970.737900766789;975.7511834482303;992.1814503034881;5868.853278667982;6048.334683184846;6130;6160.045192995093;6259.280182779039;946.1626460582435;946.3422921069928;981.4798156976814;991.9950400248097;1035.637526865606;4056.14820887611;4106.7125301229225;4125.463689720317;4127.215828992482;4138.379243113497;11776.52893884235;12075.698107547381;12129.090318225992;12153;12223.266604003788;12001.39993000364;12035.638930832089;12040.337781422106;12136.575219867262;12139.514419423302;7876.330511906437;8182.836343273101;8295.170482951657;8379.203975622306;8794.91205087941;5957.195778569838;6100.908486372797;6139.048447490646;6157.753689852356;6233.844153896008;10904.945475272572;11061.834072488758;11158.107351411705;11270.211085224284;11315.490802913724;11209.55161793519;11228.382438965935;11357.545698172311;11384.601538946097;11394.145439092266;8414.831703365777;8442.66229350819;8456.238938495564;8459.661613535389;8500.107488713844;8648.221660050625;8677.566121694017;8697.869531957153;8721.781955451164;8821.867671984797;7993.6802527898235;8030.83938321242;8069.0720567133185;8073;8122.7157049502985;7744.961275193588;7780.144184139627;7798.610069496616;7890;7913.920860791319;7323.597202153933;7494.70021199146;7501.137369202612;7502.324790768857;7511.023566936264;5195.3246078009615;5222.834672966256;5255.658382205144;5324.7870085196155;5370.892582148414;5248.212768084818;5299.33758280215;5300.204969254489;5343.064963631271;5374.005808925362 03:20:28 INFO - PROCESS | 616 | |4;prototype.html;339.2536419876272;343.6460445740886;349.2229788720097;350.27667865857;352.2356486424459;5303.064381500753;5345.705986170759;5346.251524786533;5347.395781265619;5415.512603865652;5269.6574722643;5345.866353341168;5351;5352.785888564453;5359.839204823852;4024.235395274896;4031.314676505001;4220.36694495825;4230.217409779185;4246.80889359978;4158.875233742992;4197.950512371907;4200.831966721327;4204.600562946515;4226.971053894133;3999;4172.207280616688;4181.289180839264;4200.621944025037;4205.600467955539;4056.8580099696514;4108.979455102721;4165.770882601456;4166.416701661769;4193.706440549163;3653.6163702811086;3687.207250441168;3696.872453901559;3708.5178926739623;3725.9627403725885;5166.483351664841;5192.662476938987;5204.583633309327;5210.322658054429;5211.661242019294;4524.864254072383;4739.526047395233;4742.197964773399;4744.928826067612;4763.976180119112;5469.371022332443;5472.315960504937;5538.6122971392015;5560.610757246995;5563;3990.74060186087;4050.7772072536;4061.248668996245;4081.3061779497557;4084.5302790178916;5629.57778166635;5676.091825307931;5781;5791.768329266824;5798.681072541009;4092.5907409258834;4112.712110152321;4122.154958233555;4124.566920473367;4126.5667104953545;4163;4191.847242008472;4202.85290014848;4211.157768446323;4212.157568486315;4578.839740609129;4711.293306004127;4743.02767932573;4760.857174284777;4774.570288674036;5478.534324582455;5527.447255274521;5543.833684989376;5545.473180047889;5562.360328562187;1427.4004917934492;1435.6985033142948;1435.9856401436061;1436.77011678131;1446.2840893757657;918.6738707758783;930.930180236485;975.9072888075622;1021.8467229915572;1032.5714828346302;5360.061989151961;5422.376426710979;5425.484578964992;5451.618386712971;5462.317210348707;5622.57830662702;5643.209950606918;5643.7742490299925;5694.430556944272;5697.230873832063;5172.689638621695;5415.431379705153;5437.818875957609;5497.587680923947;5563.805266815723;3034.51447768356;3038.437888990544;3050.9694903050686;3061.9846900765356;3092.4537731134433;5768.278965129359;6001.949658809691;6040.828565003409;6045.6070355426755;6058.182145410401;5845.210896528999;5850.502707269845;5854.677992710356;5875.823725288248;5889.234399528034;6013.338532761392;6026.126211699367;6034.9396506035255;6084.939150608438;6100.664463454464;5599.356074051373;5615.915761263666;5714.457126572969;5741.569382296261;5768.932747441737;5597.972010140086;5659.632518651396;5681.147827825859;5702.173184888085;5733.483986441241;5792.189093526826;5991.191189189403;6053.818385448443;6073.848153796191;6090.451859332681;4025.698072644505;4033.1127152026506;4058.6144316289906;4058.959410405976;4059.878203653895;5717.828465146053;5726.885462290814;5797.2173756542325;5835.591508594358;5844.357120716632;5472.972635136958;5565.860853478695;5568.248286481273;5584.776608935597;5596.748146333506;5515.669059856422;5547.834954659405;5610.158476228435;5615.157726341082;5659.405762395054;5313.707746073848;5504.064309067388;5529.064827310409;5534.225208470737;5588.3573389060775;5594.440555944373;5636.323641163087;5661.433856614306;5673.723412232214;5683.829485115453;5484.533814625802;5487.067198576331;5513.759404134038;5549.944500554942;5552.472515111058 03:20:28 INFO - PROCESS | 616 | |5;yui.html;817.0971076959959;820.3027426687316;821.6795449774587;839.3788596438634;884.0805562215296;14872.851271487281;15032;15131.62170945727;15132.470363537279;15168.241587920602;15187.088774673528;15257.694846103099;15309.923450382761;15322.310496027678;15348.539543813675;732.8387754693972;848.893888263967;871.1201686296854;872.8079822439054;873.4148120759074;861.8664107063383;863.8531449653542;865.8614621660536;867.5965675960678;867.8958524977012;759.4911409355744;888.2938064238913;890.0432035561764;891.0465801592298;892.5983307511614;885.6413152673177;913.4427998920653;914.1589737441569;915.638322862469;916.5234078279258;677.1569396101836;677.3937326093119;677.8407074337526;678.0778141727247;678.5284227461916;15106.924465377602;15210.847891521054;15255.466058687893;15257.923710381378;15263;14719.632009199748;14828;14867.702645947129;14914.254287285592;14956.40174393023;12904;13117.409716562777;13175.802362964563;13398.464061437531;13411.530596429177;4514.503404625489;4575.519570445089;4676.251799712064;4725.054989002213;4777.5222477752295;74769.25230747677;75481.62259188668;75748.24251757468;78953.21046789574;79261.60369198174;11444.141689373164;11629.302241865515;11823.113266504875;11964.940175299242;12162;22054.11783528673;22297.331080067623;22375.77624223737;22396.776032239468;22695.319140425483;8587.527685977331;8612.526311052827;8668.739937801876;8737.781555461164;8853.844210776528;4736.976315118472;4886.609071274361;4919.114559379274;4949.430815456197;5011.298418221452;3751.81240937952;3952.130531283113;3953.8220780064967;3983.820728067244;3984.521857377133;576.1731914702416;689.3726708695064;696.289784419899;715.4634024481638;725.5574099799119;718.8813845715398;751.099717714184;762.1844626249859;774.5894675821824;775.5811861594789;10625.149988000941;10716.249862509549;10754.838677419844;11371;11532.942335288437;679.6737565968363;724.221461928429;739.9038125043818;750.7522517569189;751.2187325181859;5091.1854103343285;5224.242484839677;5390.865228369244;5395.4604539545735;5458.590605704509;97893;98778.506107469;101446;117043;132291.33854330666;98945.50527247318;99352.50323748335;100381.49809250906;100739;102101;87801.12198878058;89766.55116724374;91695;91822;92454;9785.60857565718;10115.797684046425;10633.521491532745;10744.355338679705;10831.945840271064;51150.74424627853;51649.48350516597;51815.01002152713;52573.47426525686;52988.205176921605;68174;68562.65718671575;68745;68803.65598171977;69599.30400695928;13514.459421623025;13531.864681353061;13597.660058498617;14147;14199.077059991068;11495.54017839277;14869.330880110205;16384.426545070866;16703.582410439838;16975.405860794814;15046.097234165985;15086.849131508545;15431.15128667934;15695.921520392325;15699;13739.931300343835;13760.793588096387;14019.929900350433;14079.155250684991;14386.712265754835;14977.550673479813;15193.92403038022;15467.149306788235;15987;15996;11944.581939632071;12143;12324.876751232732;12530.122891397516;13090.0836941417;12018.939905300418;12027.699307517381;12030.939845300718;12351.3824308786;12476.18904771187 03:20:28 INFO - PROCESS | 616 | __end_tp_report 03:20:28 INFO - PROCESS | 616 | __start_cc_report 03:20:28 INFO - PROCESS | 616 | _x_x_mozilla_cycle_collect,908 03:20:28 INFO - PROCESS | 616 | __end_cc_report 03:20:28 INFO - PROCESS | 616 | __startTimestamp1469874028636__endTimestamp 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | ------- Summary: start ------- 03:20:28 INFO - PROCESS | 616 | Number of tests: 6 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | [#0] dojo.html Cycles:165 Average:5413.83 Median:5608.80 stddev:584.34 (10.4%) stddev-sans-first:562.25 03:20:28 INFO - PROCESS | 616 | Values: 3306.0 3324.3 3324.6 3357.4 3361.5 5374.1 5446.1 5451.4 5461.2 5471.0 5408.8 5461.6 5467.6 5473.2 5495.9 4856.8 4928.2 4949.3 4997.3 5062.5 4810.1 4947.1 4977.8 4984.3 4989.0 4892.8 4914.3 4965.2 4981.2 4999.6 5053.2 5062.8 5064.3 5067.5 5101.7 4791.8 4859.7 4912.2 4976.9 4990.4 5276.0 5278.7 5326.2 5357.3 5360.2 5804.2 5872.1 5874.6 5878.5 5879.9 5652.9 5671.5 5721.5 5799.6 5922.1 5381.0 5420.8 5431.5 5433.1 5439.8 5734.1 5904.4 5906.5 5907.4 5908.5 5591.7 5608.8 5641.0 5662.9 5706.9 5762.3 5801.3 5810.5 5812.3 5817.0 5617.8 5737.9 5746.2 5757.8 5765.2 5837.9 5861.8 5864.4 5883.0 5903.1 4537.7 4539.0 4541.7 4547.9 4557.8 3975.3 3989.6 4002.9 4063.0 4090.7 5534.6 5552.1 5558.4 5558.7 5587.4 5833.0 5839.4 5841.2 5843.2 5875.9 5409.9 5480.6 5507.9 5531.9 5535.9 4981.2 4986.9 5006.1 5006.7 5013.9 6075.6 6082.1 6084.0 6095.5 6100.5 5664.2 5801.2 5825.9 5835.4 5863.6 6044.6 6048.0 6064.5 6065.2 6067.6 5601.1 5693.3 5701.8 5704.4 5705.7 5782.9 5836.8 5862.0 5916.8 5922.1 5783.6 5786.3 5787.7 5788.3 5791.1 5447.6 5609.5 5739.5 5745.9 5753.8 5538.1 5616.4 5619.0 5624.9 5648.2 5674.3 5739.8 5759.9 5767.7 5768.8 5485.6 5511.3 5564.1 5638.2 5663.6 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | [#1] ext.html Cycles:180 Average:66600.46 Median:401392.00 stddev:135716.45 (33.8%) stddev-sans-first:136006.65 03:20:28 INFO - PROCESS | 616 | Values: 581.1 622.1 636.1 639.7 664.4 29483.4 32167.4 32171.8 47020.5 47916.5 49122.0 49226.5 49268.3 49354.8 49407.0 9044.5 9184.7 9193.3 9195.9 9251.3 8117.5 10716.1 10940.6 10949.5 11385.7 11095.2 11171.2 11184.2 11189.7 11194.3 8247.6 8396.8 11100.0 11202.0 11209.4 10579.5 10941.7 10949.5 10954.8 11349.9 42303.6 42939.4 46587.3 46647.8 46672.5 3806.8 3853.3 3858.8 3925.3 4050.0 34700.0 35195.0 35338.5 35789.8 36138.1 4455.5 4478.3 4496.4 4503.7 4507.5 397233.0 397351.0 400379.0 401126.0 401658.0 69159.3 69510.7 69871.7 69970.0 70059.3 39585.8 41580.6 41628.4 43106.4 43376.6 20101.7 20373.3 20447.0 20455.0 20473.4 13192.3 13547.9 13690.6 14929.7 15454.6 22044.1 22288.7 22352.3 22429.1 22459.4 8047.5 8114.7 8166.6 8186.5 8356.2 1033.0 1109.3 1126.7 1178.5 1240.8 33248.8 34262.0 34456.5 34553.3 34613.7 1037.3 1052.2 1054.2 1074.2 1145.7 15636.5 17532.5 18269.4 18294.8 18603.6 650753.0 653061.0 655510.0 655602.0 657699.0 64177.4 64826.8 64861.7 64985.7 65113.3 1376.5 1447.6 1462.2 1476.5 1496.6 34035.5 34321.5 35302.0 35474.8 35653.5 55137.7 55329.7 55691.4 55708.0 55780.0 437972.0 438294.0 438777.0 440496.0 441303.0 27021.2 29920.0 31209.0 31265.0 32820.7 52179.5 52971.2 53295.5 54073.2 54237.2 44463.6 45330.5 45526.0 45760.3 46457.3 44456.8 44961.3 45191.3 45858.0 46072.3 45253.3 45519.5 45879.0 46319.0 46581.8 37499.3 39794.8 40591.2 42464.8 42781.4 27215.0 27286.7 27294.5 27438.5 27466.0 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | [#2] jquery.html Cycles:180 Average:27014.98 Median:169388.43 stddev:71530.31 (42.2%) stddev-sans-first:71703.07 03:20:28 INFO - PROCESS | 616 | Values: 408.2 423.3 430.1 431.4 440.7 18370.5 19177.7 19657.9 19699.5 19952.1 19092.2 19255.0 19631.5 20085.0 20142.1 5660.7 5849.2 5882.5 5889.3 5894.5 1898.0 1941.3 4261.7 6007.7 6309.3 6338.6 6347.9 6371.0 6386.0 6390.9 6164.9 6262.5 6312.7 6319.7 6320.7 3882.7 4079.6 4251.8 5679.5 5686.1 21773.7 23233.4 23519.3 23617.8 23621.4 2386.9 2452.3 2730.4 2840.9 2867.9 12880.1 13518.2 13689.7 13856.4 14091.6 2026.3 2037.8 2046.0 2047.1 2069.7 74320.3 74805.6 77504.6 77694.6 78415.0 10554.9 10605.3 10987.6 11016.9 11132.1 15166.5 15211.6 15216.7 15229.4 15242.4 8118.8 8240.9 8306.0 8366.0 8410.9 8009.7 8048.4 8049.4 8115.9 8131.1 6421.6 6428.4 6519.3 6552.0 6611.0 1744.6 1746.7 1776.2 1784.4 1882.2 465.5 942.0 1011.0 1052.3 1058.1 11376.0 12096.3 12256.5 12314.4 12352.8 465.9 487.2 488.0 492.8 494.5 5719.6 6219.2 6400.7 6575.4 6648.5 335909.0 376506.0 382764.0 511862.0 513348.0 67146.7 96070.0 101287.0 103493.5 105382.0 1076.3 1102.6 1108.6 1114.2 1125.5 17531.9 17552.9 17854.0 17909.9 18152.3 42028.0 42487.2 43033.6 43135.1 43710.8 59826.0 60344.0 61627.0 61717.7 61938.4 6940.4 8124.0 8223.0 8289.9 8451.2 9205.6 9917.0 12245.4 12878.7 13366.7 11232.1 11650.5 11739.0 11777.7 12086.0 11633.0 11677.6 11791.5 12071.7 12398.6 11699.6 11727.0 11925.6 12197.3 12250.8 8597.1 8721.7 8776.6 8865.2 9071.2 10694.3 11720.4 12110.5 12119.5 12199.5 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | [#3] mootools.html Cycles:180 Average:6155.34 Median:4967.23 stddev:3606.49 (72.6%) stddev-sans-first:3598.34 03:20:28 INFO - PROCESS | 616 | Values: 1324.7 1545.4 1571.7 1780.9 1879.0 8247.2 9174.9 9444.0 9462.9 9692.3 9240.5 9431.8 9437.5 9441.4 9443.2 2158.4 2381.1 2411.5 2441.8 2515.5 2491.6 2502.4 2523.8 2527.5 2528.3 2490.3 2495.8 2497.2 2497.3 2499.5 2161.0 2344.5 2406.1 2413.2 2422.3 2287.2 2294.9 2297.5 2342.7 2393.7 9211.4 9267.9 9272.2 9303.9 9376.0 5333.6 5446.7 5452.8 5458.5 5460.5 3464.0 3524.0 3618.8 3643.0 3647.8 1164.0 1172.5 1173.1 1183.2 1187.0 13315.1 13323.6 13346.5 13389.1 13395.9 4918.6 4926.4 4929.0 4941.4 5015.4 11094.6 11178.0 11218.6 11244.4 11272.2 4993.1 5483.2 5503.2 5508.1 5509.5 4913.5 5050.6 5057.6 5074.1 5080.7 4254.9 4257.3 4287.3 4329.2 4355.8 1293.6 1342.2 1344.5 1345.2 1346.3 874.5 945.8 970.7 975.8 992.2 5868.9 6048.3 6130.0 6160.0 6259.3 946.2 946.3 981.5 992.0 1035.6 4056.1 4106.7 4125.5 4127.2 4138.4 11776.5 12075.7 12129.1 12153.0 12223.3 12001.4 12035.6 12040.3 12136.6 12139.5 7876.3 8182.8 8295.2 8379.2 8794.9 5957.2 6100.9 6139.0 6157.8 6233.8 10904.9 11061.8 11158.1 11270.2 11315.5 11209.6 11228.4 11357.5 11384.6 11394.1 8414.8 8442.7 8456.2 8459.7 8500.1 8648.2 8677.6 8697.9 8721.8 8821.9 7993.7 8030.8 8069.1 8073.0 8122.7 7745.0 7780.1 7798.6 7890.0 7913.9 7323.6 7494.7 7501.1 7502.3 7511.0 5195.3 5222.8 5255.7 5324.8 5370.9 5248.2 5299.3 5300.2 5343.1 5374.0 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | [#4] prototype.html Cycles:180 Average:4722.40 Median:5418.94 stddev:1385.42 (25.6%) stddev-sans-first:1349.68 03:20:28 INFO - PROCESS | 616 | Values: 339.3 343.6 349.2 350.3 352.2 5303.1 5345.7 5346.3 5347.4 5415.5 5269.7 5345.9 5351.0 5352.8 5359.8 4024.2 4031.3 4220.4 4230.2 4246.8 4158.9 4198.0 4200.8 4204.6 4227.0 3999.0 4172.2 4181.3 4200.6 4205.6 4056.9 4109.0 4165.8 4166.4 4193.7 3653.6 3687.2 3696.9 3708.5 3726.0 5166.5 5192.7 5204.6 5210.3 5211.7 4524.9 4739.5 4742.2 4744.9 4764.0 5469.4 5472.3 5538.6 5560.6 5563.0 3990.7 4050.8 4061.2 4081.3 4084.5 5629.6 5676.1 5781.0 5791.8 5798.7 4092.6 4112.7 4122.2 4124.6 4126.6 4163.0 4191.8 4202.9 4211.2 4212.2 4578.8 4711.3 4743.0 4760.9 4774.6 5478.5 5527.4 5543.8 5545.5 5562.4 1427.4 1435.7 1436.0 1436.8 1446.3 918.7 930.9 975.9 1021.8 1032.6 5360.1 5422.4 5425.5 5451.6 5462.3 5622.6 5643.2 5643.8 5694.4 5697.2 5172.7 5415.4 5437.8 5497.6 5563.8 3034.5 3038.4 3051.0 3062.0 3092.5 5768.3 6001.9 6040.8 6045.6 6058.2 5845.2 5850.5 5854.7 5875.8 5889.2 6013.3 6026.1 6034.9 6084.9 6100.7 5599.4 5615.9 5714.5 5741.6 5768.9 5598.0 5659.6 5681.1 5702.2 5733.5 5792.2 5991.2 6053.8 6073.8 6090.5 4025.7 4033.1 4058.6 4059.0 4059.9 5717.8 5726.9 5797.2 5835.6 5844.4 5473.0 5565.9 5568.2 5584.8 5596.7 5515.7 5547.8 5610.2 5615.2 5659.4 5313.7 5504.1 5529.1 5534.2 5588.4 5594.4 5636.3 5661.4 5673.7 5683.8 5484.5 5487.1 5513.8 5549.9 5552.5 03:20:28 INFO - PROCESS | 616 | 03:20:28 INFO - PROCESS | 616 | [#5] yui.html Cycles:180 Average:21127.93 Median:4545.01 stddev:29553.75 (650.2%) stddev-sans-first:29597.31 03:20:28 INFO - PROCESS | 616 | Values: 817.1 820.3 821.7 839.4 884.1 14872.9 15032.0 15131.6 15132.5 15168.2 15187.1 15257.7 15309.9 15322.3 15348.5 732.8 848.9 871.1 872.8 873.4 861.9 863.9 865.9 867.6 867.9 759.5 888.3 890.0 891.0 892.6 885.6 913.4 914.2 915.6 916.5 677.2 677.4 677.8 678.1 678.5 15106.9 15210.8 15255.5 15257.9 15263.0 14719.6 14828.0 14867.7 14914.3 14956.4 12904.0 13117.4 13175.8 13398.5 13411.5 4514.5 4575.5 4676.3 4725.1 4777.5 74769.3 75481.6 75748.2 78953.2 79261.6 11444.1 11629.3 11823.1 11964.9 12162.0 22054.1 22297.3 22375.8 22396.8 22695.3 8587.5 8612.5 8668.7 8737.8 8853.8 4737.0 4886.6 4919.1 4949.4 5011.3 3751.8 3952.1 3953.8 3983.8 3984.5 576.2 689.4 696.3 715.5 725.6 718.9 751.1 762.2 774.6 775.6 10625.1 10716.2 10754.8 11371.0 11532.9 679.7 724.2 739.9 750.8 751.2 5091.2 5224.2 5390.9 5395.5 5458.6 97893.0 98778.5 101446.0 117043.0 132291.3 98945.5 99352.5 100381.5 100739.0 102101.0 87801.1 89766.6 91695.0 91822.0 92454.0 9785.6 10115.8 10633.5 10744.4 10831.9 51150.7 51649.5 51815.0 52573.5 52988.2 68174.0 68562.7 68745.0 68803.7 69599.3 13514.5 13531.9 13597.7 14147.0 14199.1 11495.5 14869.3 16384.4 16703.6 16975.4 15046.1 15086.8 15431.2 15695.9 15699.0 13739.9 13760.8 14019.9 14079.2 14386.7 14977.6 15193.9 15467.1 15987.0 15996.0 11944.6 12143.0 12324.9 12530.1 13090.1 12018.9 12027.7 12030.9 12351.4 12476.2 03:20:28 INFO - PROCESS | 616 | -------- Summary: end -------- 03:20:28 INFO - PROCESS | 616 | 03:20:29 INFO - TEST-INFO | 616: exit 0 03:20:29 INFO - TEST-OK | dromaeo_css | took 1112182ms 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 816 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps8BHL0/profile http://localhost:47576/getInfo.html) 03:20:33 INFO - PROCESS | 816 | __metrics Screen width/height:1600/1200 03:20:33 INFO - PROCESS | 816 | colorDepth:24 03:20:33 INFO - PROCESS | 816 | Browser inner width/height: 1024/697 03:20:33 INFO - PROCESS | 816 | __metrics 03:20:33 INFO - PROCESS | 816 | 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:33 ERROR - PROCESS | 816 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:20:34 INFO - TEST-INFO | 816: exit 0 03:20:34 INFO - Browser initialized. 03:20:34 INFO - Running cycle 1/1 for kraken test... 03:20:34 INFO - TEST-INFO | started process 908 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps8BHL0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:46 INFO - PROCESS | 908 | RSS: Main: 145408000 03:20:46 INFO - PROCESS | 908 | 03:20:49 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47576/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:49 INFO - PROCESS | 908 | RSS: Main: 194899968 03:20:49 INFO - PROCESS | 908 | 03:20:52 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47576/tests/kraken/driver.html?testName=audio-dft) 03:20:53 INFO - PROCESS | 908 | RSS: Main: 192892928 03:20:53 INFO - PROCESS | 908 | 03:20:56 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47576/tests/kraken/driver.html?testName=audio-fft) 03:20:56 INFO - PROCESS | 908 | RSS: Main: 279130112 03:20:56 INFO - PROCESS | 908 | 03:20:58 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47576/tests/kraken/driver.html?testName=audio-oscillator) 03:20:59 INFO - PROCESS | 908 | RSS: Main: 187830272 03:20:59 INFO - PROCESS | 908 | 03:21:01 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47576/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:02 INFO - PROCESS | 908 | RSS: Main: 188440576 03:21:02 INFO - PROCESS | 908 | 03:21:04 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47576/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:05 INFO - PROCESS | 908 | RSS: Main: 222986240 03:21:05 INFO - PROCESS | 908 | 03:21:08 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47576/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:09 INFO - PROCESS | 908 | RSS: Main: 222523392 03:21:09 INFO - PROCESS | 908 | 03:21:12 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47576/tests/kraken/driver.html?testName=json-parse-financial) 03:21:12 INFO - PROCESS | 908 | RSS: Main: 222199808 03:21:12 INFO - PROCESS | 908 | 03:21:14 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47576/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:21:14 INFO - PROCESS | 908 | RSS: Main: 201244672 03:21:14 INFO - PROCESS | 908 | 03:21:16 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:21:17 INFO - PROCESS | 908 | RSS: Main: 202330112 03:21:17 INFO - PROCESS | 908 | 03:21:19 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:21:19 INFO - PROCESS | 908 | RSS: Main: 209182720 03:21:19 INFO - PROCESS | 908 | 03:21:22 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:23 INFO - PROCESS | 908 | RSS: Main: 214056960 03:21:23 INFO - PROCESS | 908 | 03:21:25 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:26 INFO - PROCESS | 908 | RSS: Main: 213864448 03:21:26 INFO - PROCESS | 908 | 03:21:27 INFO - PROCESS | 908 | Cycle 1(1): loaded http://localhost:47576/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47576/tests/kraken/driver.html?testName=ai-astar) 03:21:28 INFO - PROCESS | 908 | RSS: Main: 257114112 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | __start_tp_report 03:21:28 INFO - PROCESS | 908 | _x_x_mozilla_page_load 03:21:28 INFO - PROCESS | 908 | _x_x_mozilla_page_load_details 03:21:28 INFO - PROCESS | 908 | |i|pagename|runs| 03:21:28 INFO - PROCESS | 908 | |0;ai-astar;112;104;112;109;111;110;108;110;110;110 03:21:28 INFO - PROCESS | 908 | |1;audio-beat-detection;157;123;129;169;123;126;156;131;167;124 03:21:28 INFO - PROCESS | 908 | |2;audio-dft;160;159;162;163;160;161;160;160;159;160 03:21:28 INFO - PROCESS | 908 | |3;audio-fft;75;74;75;109;76;75;73;74;105;75 03:21:28 INFO - PROCESS | 908 | |4;audio-oscillator;84;83;111;85;83;86;116;85;85;125 03:21:28 INFO - PROCESS | 908 | |5;imaging-gaussian-blur;117;116;116;117;118;117;117;116;117;117 03:21:28 INFO - PROCESS | 908 | |6;imaging-darkroom;146;146;146;145;146;146;147;145;145;145 03:21:28 INFO - PROCESS | 908 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;94 03:21:28 INFO - PROCESS | 908 | |8;json-parse-financial;50;50;52;51;50;52;52;50;53;51 03:21:28 INFO - PROCESS | 908 | |9;json-stringify-tinderbox;61;61;61;61;60;61;60;61;61;61 03:21:28 INFO - PROCESS | 908 | |10;stanford-crypto-aes;79;76;77;76;76;77;81;82;106;78 03:21:28 INFO - PROCESS | 908 | |11;stanford-crypto-ccm;144;127;117;125;115;122;115;132;121;121 03:21:28 INFO - PROCESS | 908 | |12;stanford-crypto-pbkdf2;158;153;152;152;152;153;156;158;150;150 03:21:28 INFO - PROCESS | 908 | |13;stanford-crypto-sha256-iterative;64;61;61;62;62;60;66;61;61;63 03:21:28 INFO - PROCESS | 908 | __end_tp_report 03:21:28 INFO - PROCESS | 908 | __start_cc_report 03:21:28 INFO - PROCESS | 908 | _x_x_mozilla_cycle_collect,1609 03:21:28 INFO - PROCESS | 908 | __end_cc_report 03:21:28 INFO - PROCESS | 908 | __startTimestamp1469874088197__endTimestamp 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | ------- Summary: start ------- 03:21:28 INFO - PROCESS | 908 | Number of tests: 14 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:2.32 (2.1%) stddev-sans-first:2.29 03:21:28 INFO - PROCESS | 908 | Values: 112.0 104.0 112.0 109.0 111.0 110.0 108.0 110.0 110.0 110.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:143.50 stddev:19.28 (13.4%) stddev-sans-first:19.50 03:21:28 INFO - PROCESS | 908 | Values: 157.0 123.0 129.0 169.0 123.0 126.0 156.0 131.0 167.0 124.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 03:21:28 INFO - PROCESS | 908 | Values: 160.0 159.0 162.0 163.0 160.0 161.0 160.0 160.0 159.0 160.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.71 (18.3%) stddev-sans-first:14.36 03:21:28 INFO - PROCESS | 908 | Values: 75.0 74.0 75.0 109.0 76.0 75.0 73.0 74.0 105.0 75.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:84.50 stddev:16.27 (19.3%) stddev-sans-first:16.82 03:21:28 INFO - PROCESS | 908 | Values: 84.0 83.0 111.0 85.0 83.0 86.0 116.0 85.0 85.0 125.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 03:21:28 INFO - PROCESS | 908 | Values: 117.0 116.0 116.0 117.0 118.0 117.0 117.0 116.0 117.0 117.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 03:21:28 INFO - PROCESS | 908 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 147.0 145.0 145.0 145.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 03:21:28 INFO - PROCESS | 908 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.10 (2.1%) stddev-sans-first:1.09 03:21:28 INFO - PROCESS | 908 | Values: 50.0 50.0 52.0 51.0 50.0 52.0 52.0 50.0 53.0 51.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 03:21:28 INFO - PROCESS | 908 | Values: 61.0 61.0 61.0 61.0 60.0 61.0 60.0 61.0 61.0 61.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:77.50 stddev:9.10 (11.7%) stddev-sans-first:9.63 03:21:28 INFO - PROCESS | 908 | Values: 79.0 76.0 77.0 76.0 76.0 77.0 81.0 82.0 106.0 78.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:123.50 stddev:8.86 (7.2%) stddev-sans-first:5.68 03:21:28 INFO - PROCESS | 908 | Values: 144.0 127.0 117.0 125.0 115.0 122.0 115.0 132.0 121.0 121.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.00 stddev:2.95 (1.9%) stddev-sans-first:2.62 03:21:28 INFO - PROCESS | 908 | Values: 158.0 153.0 152.0 152.0 152.0 153.0 156.0 158.0 150.0 150.0 03:21:28 INFO - PROCESS | 908 | 03:21:28 INFO - PROCESS | 908 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.79 (2.9%) stddev-sans-first:1.76 03:21:28 INFO - PROCESS | 908 | Values: 64.0 61.0 61.0 62.0 62.0 60.0 66.0 61.0 61.0 63.0 03:21:28 INFO - PROCESS | 908 | -------- Summary: end -------- 03:21:28 INFO - PROCESS | 908 | 03:21:29 INFO - TEST-INFO | 908: exit 0 03:21:29 INFO - TEST-OK | kraken | took 59864ms 03:21:29 INFO - SUITE-END | took 1172s 03:21:29 INFO - Completed test suite (00:19:32) 03:21:29 INFO - javascript benchmark 03:21:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3305.983470082649, 3324.301896601712, 3324.5678061851954, 3357.429237029705, 3361.5461912641786, 5374.059539580572, 5446.101393270111, 5451.427600101992, 5461.15352120421, 5471.0, 5408.810691625794, 5461.590380721452, 5467.562594992401, 5473.2063850741615, 5495.945040549594, 4856.7571621418965, 4928.211486162215, 4949.28235405866, 4997.325361076263, 5062.468440813716, 4810.134175848363, 4947.1342515059905, 4977.800887964477, 4984.327115839352, 4988.975055124737, 4892.755362231892, 4914.287428322891, 4965.180745177059, 4981.153203955337, 4999.575036121916, 5053.242013697938, 5062.848114556551, 5064.3163172971545, 5067.5185857343495, 5101.7194054327, 4791.784369703337, 4859.659823812334, 4912.238603016538, 4976.900461990776, 4990.351254336913, 5276.023935571893, 5278.736063196863, 5326.227696983954, 5357.303550538444, 5360.195970604441, 5804.158397032449, 5872.119182122673, 5874.647521148745, 5878.50032747217, 5879.8530036749, 5652.858678533028, 5671.546276297928, 5721.4850663440075, 5799.623024503394, 5922.082077278029, 5381.031414345416, 5420.810271640513, 5431.456854314616, 5433.0763770159165, 5439.809606663787, 5734.139879018097, 5904.380040095728, 5906.527477801766, 5907.438793314628, 5908.527317814564, 5591.720413979285, 5608.803691870765, 5640.97179514108, 5662.943370566324, 5706.857328566819, 5762.250907382014, 5801.3038435388025, 5810.5061069809535, 5812.273465816773, 5817.0401883689565, 5617.8033768817895, 5737.942620573825, 5746.224259724967, 5757.798477053283, 5765.1928729977835, 5837.941620583825, 5861.7655293787775, 5864.44287792659, 5883.0, 5903.055511118287, 4537.705049171794, 4539.02410981634, 4541.72749635023, 4547.886302842389, 4557.772111394417, 3975.264576053439, 3989.5810939851476, 4002.8799136025964, 4063.045184381668, 4090.734102283342, 5534.584906132056, 5552.056150454352, 5558.416366281563, 5558.722063896789, 5587.357453892773, 5833.037548804485, 5839.357670656224, 5841.211436456109, 5843.24037875082, 5875.882482350329, 5409.918851217235, 5480.561555075664, 5507.862303442366, 5531.917021244684, 5535.916961245584, 4981.227909673934, 4986.8753281168265, 5006.0989021975665, 5006.724630145377, 5013.874653133701, 6075.635461872302, 6082.118092876508, 6084.0265557511475, 6095.542834287357, 6100.450959413585, 5664.235328230801, 5801.1878337032, 5825.883482330414, 5835.44563266506, 5863.648181109147, 6044.637321760709, 6048.032314829607, 6064.484518815773, 6065.2418447694035, 6067.635941843503, 5601.131824567198, 5693.28833895763, 5701.828945131652, 5704.429557044263, 5705.657660540381, 5782.942170578241, 5836.795712150054, 5862.0, 5916.792912248051, 5922.082077278029, 5783.624064435799, 5786.276715410574, 5787.652740835563, 5788.27646544182, 5791.102379131242, 5447.564194864481, 5609.467100625434, 5739.512141468022, 5745.8563535911935, 5753.798617048383, 5538.0585300499815, 5616.354119276172, 5618.971905140448, 5624.887502250014, 5648.209250704823, 5674.347450043133, 5739.799107031234, 5759.884802304015, 5767.68277744728, 5768.798092066758, 5485.61600687964, 5511.338639363302, 5564.081926482085, 5638.182463542846, 5663.603547751618], "value": 5377.269555951485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.1167026120297, 622.1197006236176, 636.0903907412398, 639.7441023590566, 664.4119953840849, 29483.41033179335, 32167.35665286693, 32171.83914080429, 47020.529794702044, 47916.52083479164, 49122.0, 49226.50773492264, 49268.26097608539, 49354.75322623391, 49407.0, 9044.457332560067, 9184.678536251233, 9193.310501712365, 9195.862062069074, 9251.25989920808, 8117.472364296332, 10716.089132423753, 10940.617078402258, 10949.507272172732, 11385.715357116096, 11095.167862410311, 11171.16216283778, 11184.217104802627, 11189.664310070712, 11194.328340299568, 8247.628856701464, 8396.790080247982, 11100.0, 11202.047825934724, 11209.439528023608, 10579.523921423555, 10941.671749847517, 10949.45252737368, 10954.780904381947, 11349.943250283779, 42303.57696423058, 42939.35590966138, 46587.30119048217, 46647.76676116631, 46672.5332746675, 3806.847726090953, 3853.2678791029616, 3858.8070596470066, 3925.3130702127014, 4049.97975010126, 34700.0, 35195.0, 35338.469922951175, 35789.82105089484, 36138.09654758626, 4455.509893911667, 4478.305862591304, 4496.437945256843, 4503.684742068057, 4507.549245075499, 397233.0, 397351.0, 400379.0, 401126.0, 401658.0, 69159.30840691629, 69510.65244673846, 69871.65064174647, 69970.0, 70059.2994070063, 39585.80207098946, 41580.58419415767, 41628.375574365804, 43106.35340469895, 43376.566234337886, 20101.698474522895, 20373.286934957207, 20447.0, 20455.0, 20473.385798426072, 13192.274424906722, 13547.932260338832, 13690.589282321545, 14929.70140597182, 15454.613634659, 22044.11823527073, 22288.66567001496, 22352.329430117123, 22429.10283588638, 22459.43851403728, 8047.51714897108, 8114.715984940499, 8166.591670416456, 8186.467879587927, 8356.206160414751, 1032.9845052324222, 1109.3399427340719, 1126.6563698072002, 1178.4873579992732, 1240.7704574653717, 33248.83375583107, 34262.0, 34456.48315275273, 34553.30893382118, 34613.65386346104, 1037.2946396450336, 1052.1744772819152, 1054.246213957009, 1074.2319241742036, 1145.7479354541988, 15636.530904072895, 17532.474025779247, 18269.36057238017, 18294.817051829312, 18603.627927441645, 650753.0, 653061.0, 655510.0, 655602.0, 657699.0, 64177.432902684304, 64826.758662066706, 64861.67569162124, 64985.67507162434, 65113.348866511675, 1376.4700590272616, 1447.6163816588612, 1462.2396353895915, 1476.4758510728752, 1496.6108811709034, 34035.48946765751, 34321.48517772186, 35302.0, 35474.82262588671, 35653.465198021535, 55137.72431137819, 55329.723351382985, 55691.44308556862, 55708.0, 55780.0, 437972.0, 438294.0, 438777.0, 440496.0, 441303.0, 27021.189364319103, 29920.0, 31209.0, 31265.0, 32820.67179328176, 52179.47820521746, 52971.205431917784, 53295.46704532905, 54073.18890216571, 54237.18644220419, 44463.55536444724, 45330.54669453263, 45526.0, 45760.31359529543, 46457.303140453594, 44456.77771611121, 44961.32558011567, 45191.32213016873, 45858.0, 46072.308915365626, 45253.32120018268, 45519.54480455153, 45879.0, 46319.0, 46581.76709116433, 37499.25001500009, 39794.80102599468, 40591.1881762369, 42464.78767606142, 42781.35827962521, 27215.0, 27286.727132728418, 27294.524311417892, 27438.45123097567, 27466.0], "value": 21813.01073367719, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.1591920643473, 423.26352147263754, 430.1161113910913, 431.4218946611541, 440.7349526481071, 18370.540736481576, 19177.71233431498, 19657.901710491427, 19699.5075123122, 19952.102155403005, 19092.23631054756, 19255.0, 19631.509212269666, 20085.0, 20142.093605787737, 5660.716964151797, 5849.181114643953, 5882.499987501046, 5889.293284805808, 5894.4989675877605, 1898.0035481372252, 1941.2817257614702, 4261.680373971949, 6007.699615019232, 6309.337519560426, 6338.587991780542, 6347.904781428282, 6371.044343348512, 6386.042093685961, 6390.936090639104, 6164.907526387108, 6262.499000079983, 6312.715927783261, 6319.74721011159, 6320.683965801692, 3882.7087968402484, 4079.5920407958965, 4251.829926802924, 5679.460451257124, 5686.0617998030275, 21773.673394899088, 23233.419164520852, 23519.294421167222, 23617.76382236173, 23621.409464763346, 2386.9283921482383, 2452.288836237489, 2730.426610411816, 2840.8863645454157, 2867.856607169633, 12880.098393112487, 13518.188908665512, 13689.726205475934, 13856.445742170301, 14091.647708807259, 2026.2604149485478, 2037.8165965063067, 2046.0, 2047.1299697628597, 2069.689546568012, 74320.25679743242, 74805.62597187034, 77504.61247693782, 77694.61152694256, 78415.0, 10554.947225263824, 10605.257631965842, 10987.560497580007, 11016.944915275373, 11132.10943124548, 15166.545003649908, 15211.61970950713, 15216.695666086616, 15229.390824367123, 15242.3903043877, 8118.797030074295, 8240.876386854203, 8305.95847020761, 8366.0, 8410.87383689233, 8009.679612815538, 8048.356131509582, 8049.3962952778775, 8115.8782618260775, 8131.064927533291, 6421.550491465552, 6428.389303016206, 6519.348065193442, 6551.9844424114335, 6611.041398997118, 1744.5551384396897, 1746.6855965926254, 1776.209586733891, 1784.4289827255154, 1882.2282864025683, 465.54144167994497, 941.9858702119474, 1010.9898901010896, 1052.273930987616, 1058.111186603257, 11376.0, 12096.33470159132, 12256.509739610494, 12314.384280785926, 12352.814707779391, 465.8586463165259, 487.24963556123174, 487.97628415462896, 492.8078049560674, 494.48820470812535, 5719.5996280260815, 6219.1604133442315, 6400.71196796136, 6575.4410875076155, 6648.501362397839, 335909.0, 376506.0, 382764.0, 511862.0, 513348.0, 67146.66426667836, 96070.0, 101287.0, 103493.48253258686, 105382.0, 1076.3272954403499, 1102.6306187427301, 1108.6064447121316, 1114.1810769084555, 1125.5441546173813, 17531.912340438725, 17552.91223543874, 17854.0, 17909.910450447664, 18152.27390904402, 42028.0, 42487.15025699531, 43033.56966430421, 43135.13729725325, 43710.781446092566, 59826.0, 60344.0, 61627.0, 61717.691411542655, 61938.38061619326, 6940.41006514452, 8124.0, 8222.958885205775, 8289.875651865106, 8451.23938845507, 9205.58574864146, 9916.950415247878, 12245.387730613256, 12878.678033048875, 13366.732665346834, 11232.101431885267, 11650.53397864076, 11739.0, 11777.705557361134, 12085.972692321251, 11633.0, 11677.649670509898, 11791.528338866348, 12071.698207544881, 12398.62804115878, 11699.590514332298, 11727.0, 11925.642230733092, 12197.329146897006, 12250.75498490043, 8597.140285971353, 8721.65113395457, 8776.605052772513, 8865.20213180791, 9071.228945539438, 10694.251402401756, 11720.355380454157, 12110.45502952387, 12119.515219391125, 12199.51201951912], "value": 8875.866567766481, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.7014932817287, 1545.4359158906998, 1571.7170909236336, 1780.928762849486, 1878.9906050469754, 8247.216514431128, 9174.90825091749, 9444.0, 9462.905370946288, 9692.27307951903, 9240.491772952484, 9431.764205894857, 9437.480938548377, 9441.386309889853, 9443.197328227092, 2158.438805910459, 2381.1070848431837, 2411.4935863468604, 2441.8046556275453, 2515.4843257132243, 2491.6013437849947, 2502.436951685874, 2523.785478234343, 2527.545041892468, 2528.254165021314, 2490.3151633300804, 2495.800335973118, 2497.225859983411, 2497.2633073243437, 2499.450120973383, 2161.0275376080826, 2344.507653392781, 2406.073661640281, 2413.2036427978724, 2422.2854257993936, 2287.2452090809993, 2294.908203671851, 2297.5404919016264, 2342.7188737351457, 2393.7247216570145, 9211.447313161165, 9267.907320926839, 9272.165505104573, 9303.860442093373, 9375.953120234424, 5333.626646134772, 5446.673199608036, 5452.83641490752, 5458.4814442627885, 5460.453954604547, 3463.9653603463894, 3523.982380088083, 3618.819059047037, 3642.963570364289, 3647.8358473868475, 1163.97090072748, 1172.5485688010178, 1173.1142987044811, 1183.1776915044047, 1187.044429234466, 13315.134516256509, 13323.600291991255, 13346.532871349456, 13389.062765606412, 13395.933020334836, 4918.606511479073, 4926.4088309403105, 4929.0388374267395, 4941.382327209099, 5015.423226328946, 11094.55621775136, 11178.049865761339, 11218.607348742755, 11244.437778111062, 11272.21094523392, 4993.0762808879745, 5483.177523371526, 5503.229547863302, 5508.14623733322, 5509.47659972302, 4913.484084171182, 5050.570701490415, 5057.570106540912, 5074.13739664258, 5080.720560369141, 4254.914901702011, 4257.318828987347, 4287.2711639021445, 4329.155814616182, 4355.847545335898, 1293.6377814212035, 1342.2080972226433, 1344.5428554291589, 1345.2264947654942, 1346.3268365817091, 874.4622057434632, 945.7588314979636, 970.737900766789, 975.7511834482303, 992.1814503034881, 5868.853278667982, 6048.334683184846, 6130.0, 6160.045192995093, 6259.280182779039, 946.1626460582435, 946.3422921069928, 981.4798156976814, 991.9950400248097, 1035.637526865606, 4056.14820887611, 4106.7125301229225, 4125.463689720317, 4127.215828992482, 4138.379243113497, 11776.52893884235, 12075.698107547381, 12129.090318225992, 12153.0, 12223.266604003788, 12001.39993000364, 12035.638930832089, 12040.337781422106, 12136.575219867262, 12139.514419423302, 7876.330511906437, 8182.836343273101, 8295.170482951657, 8379.203975622306, 8794.91205087941, 5957.195778569838, 6100.908486372797, 6139.048447490646, 6157.753689852356, 6233.844153896008, 10904.945475272572, 11061.834072488758, 11158.107351411705, 11270.211085224284, 11315.490802913724, 11209.55161793519, 11228.382438965935, 11357.545698172311, 11384.601538946097, 11394.145439092266, 8414.831703365777, 8442.66229350819, 8456.238938495564, 8459.661613535389, 8500.107488713844, 8648.221660050625, 8677.566121694017, 8697.869531957153, 8721.781955451164, 8821.867671984797, 7993.6802527898235, 8030.83938321242, 8069.0720567133185, 8073.0, 8122.7157049502985, 7744.961275193588, 7780.144184139627, 7798.610069496616, 7890.0, 7913.920860791319, 7323.597202153933, 7494.70021199146, 7501.137369202612, 7502.324790768857, 7511.023566936264, 5195.3246078009615, 5222.834672966256, 5255.658382205144, 5324.7870085196155, 5370.892582148414, 5248.212768084818, 5299.33758280215, 5300.204969254489, 5343.064963631271, 5374.005808925362], "value": 4877.402585758205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.2536419876272, 343.6460445740886, 349.2229788720097, 350.27667865857, 352.2356486424459, 5303.064381500753, 5345.705986170759, 5346.251524786533, 5347.395781265619, 5415.512603865652, 5269.6574722643, 5345.866353341168, 5351.0, 5352.785888564453, 5359.839204823852, 4024.235395274896, 4031.314676505001, 4220.36694495825, 4230.217409779185, 4246.80889359978, 4158.875233742992, 4197.950512371907, 4200.831966721327, 4204.600562946515, 4226.971053894133, 3999.0, 4172.207280616688, 4181.289180839264, 4200.621944025037, 4205.600467955539, 4056.8580099696514, 4108.979455102721, 4165.770882601456, 4166.416701661769, 4193.706440549163, 3653.6163702811086, 3687.207250441168, 3696.872453901559, 3708.5178926739623, 3725.9627403725885, 5166.483351664841, 5192.662476938987, 5204.583633309327, 5210.322658054429, 5211.661242019294, 4524.864254072383, 4739.526047395233, 4742.197964773399, 4744.928826067612, 4763.976180119112, 5469.371022332443, 5472.315960504937, 5538.6122971392015, 5560.610757246995, 5563.0, 3990.74060186087, 4050.7772072536, 4061.248668996245, 4081.3061779497557, 4084.5302790178916, 5629.57778166635, 5676.091825307931, 5781.0, 5791.768329266824, 5798.681072541009, 4092.5907409258834, 4112.712110152321, 4122.154958233555, 4124.566920473367, 4126.5667104953545, 4163.0, 4191.847242008472, 4202.85290014848, 4211.157768446323, 4212.157568486315, 4578.839740609129, 4711.293306004127, 4743.02767932573, 4760.857174284777, 4774.570288674036, 5478.534324582455, 5527.447255274521, 5543.833684989376, 5545.473180047889, 5562.360328562187, 1427.4004917934492, 1435.6985033142948, 1435.9856401436061, 1436.77011678131, 1446.2840893757657, 918.6738707758783, 930.930180236485, 975.9072888075622, 1021.8467229915572, 1032.5714828346302, 5360.061989151961, 5422.376426710979, 5425.484578964992, 5451.618386712971, 5462.317210348707, 5622.57830662702, 5643.209950606918, 5643.7742490299925, 5694.430556944272, 5697.230873832063, 5172.689638621695, 5415.431379705153, 5437.818875957609, 5497.587680923947, 5563.805266815723, 3034.51447768356, 3038.437888990544, 3050.9694903050686, 3061.9846900765356, 3092.4537731134433, 5768.278965129359, 6001.949658809691, 6040.828565003409, 6045.6070355426755, 6058.182145410401, 5845.210896528999, 5850.502707269845, 5854.677992710356, 5875.823725288248, 5889.234399528034, 6013.338532761392, 6026.126211699367, 6034.9396506035255, 6084.939150608438, 6100.664463454464, 5599.356074051373, 5615.915761263666, 5714.457126572969, 5741.569382296261, 5768.932747441737, 5597.972010140086, 5659.632518651396, 5681.147827825859, 5702.173184888085, 5733.483986441241, 5792.189093526826, 5991.191189189403, 6053.818385448443, 6073.848153796191, 6090.451859332681, 4025.698072644505, 4033.1127152026506, 4058.6144316289906, 4058.959410405976, 4059.878203653895, 5717.828465146053, 5726.885462290814, 5797.2173756542325, 5835.591508594358, 5844.357120716632, 5472.972635136958, 5565.860853478695, 5568.248286481273, 5584.776608935597, 5596.748146333506, 5515.669059856422, 5547.834954659405, 5610.158476228435, 5615.157726341082, 5659.405762395054, 5313.707746073848, 5504.064309067388, 5529.064827310409, 5534.225208470737, 5588.3573389060775, 5594.440555944373, 5636.323641163087, 5661.433856614306, 5673.723412232214, 5683.829485115453, 5484.533814625802, 5487.067198576331, 5513.759404134038, 5549.944500554942, 5552.472515111058], "value": 4285.820250405575, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.0971076959959, 820.3027426687316, 821.6795449774587, 839.3788596438634, 884.0805562215296, 14872.851271487281, 15032.0, 15131.62170945727, 15132.470363537279, 15168.241587920602, 15187.088774673528, 15257.694846103099, 15309.923450382761, 15322.310496027678, 15348.539543813675, 732.8387754693972, 848.893888263967, 871.1201686296854, 872.8079822439054, 873.4148120759074, 861.8664107063383, 863.8531449653542, 865.8614621660536, 867.5965675960678, 867.8958524977012, 759.4911409355744, 888.2938064238913, 890.0432035561764, 891.0465801592298, 892.5983307511614, 885.6413152673177, 913.4427998920653, 914.1589737441569, 915.638322862469, 916.5234078279258, 677.1569396101836, 677.3937326093119, 677.8407074337526, 678.0778141727247, 678.5284227461916, 15106.924465377602, 15210.847891521054, 15255.466058687893, 15257.923710381378, 15263.0, 14719.632009199748, 14828.0, 14867.702645947129, 14914.254287285592, 14956.40174393023, 12904.0, 13117.409716562777, 13175.802362964563, 13398.464061437531, 13411.530596429177, 4514.503404625489, 4575.519570445089, 4676.251799712064, 4725.054989002213, 4777.5222477752295, 74769.25230747677, 75481.62259188668, 75748.24251757468, 78953.21046789574, 79261.60369198174, 11444.141689373164, 11629.302241865515, 11823.113266504875, 11964.940175299242, 12162.0, 22054.11783528673, 22297.331080067623, 22375.77624223737, 22396.776032239468, 22695.319140425483, 8587.527685977331, 8612.526311052827, 8668.739937801876, 8737.781555461164, 8853.844210776528, 4736.976315118472, 4886.609071274361, 4919.114559379274, 4949.430815456197, 5011.298418221452, 3751.81240937952, 3952.130531283113, 3953.8220780064967, 3983.820728067244, 3984.521857377133, 576.1731914702416, 689.3726708695064, 696.289784419899, 715.4634024481638, 725.5574099799119, 718.8813845715398, 751.099717714184, 762.1844626249859, 774.5894675821824, 775.5811861594789, 10625.149988000941, 10716.249862509549, 10754.838677419844, 11371.0, 11532.942335288437, 679.6737565968363, 724.221461928429, 739.9038125043818, 750.7522517569189, 751.2187325181859, 5091.1854103343285, 5224.242484839677, 5390.865228369244, 5395.4604539545735, 5458.590605704509, 97893.0, 98778.506107469, 101446.0, 117043.0, 132291.33854330666, 98945.50527247318, 99352.50323748335, 100381.49809250906, 100739.0, 102101.0, 87801.12198878058, 89766.55116724374, 91695.0, 91822.0, 92454.0, 9785.60857565718, 10115.797684046425, 10633.521491532745, 10744.355338679705, 10831.945840271064, 51150.74424627853, 51649.48350516597, 51815.01002152713, 52573.47426525686, 52988.205176921605, 68174.0, 68562.65718671575, 68745.0, 68803.65598171977, 69599.30400695928, 13514.459421623025, 13531.864681353061, 13597.660058498617, 14147.0, 14199.077059991068, 11495.54017839277, 14869.330880110205, 16384.426545070866, 16703.582410439838, 16975.405860794814, 15046.097234165985, 15086.849131508545, 15431.15128667934, 15695.921520392325, 15699.0, 13739.931300343835, 13760.793588096387, 14019.929900350433, 14079.155250684991, 14386.712265754835, 14977.550673479813, 15193.92403038022, 15467.149306788235, 15987.0, 15996.0, 11944.581939632071, 12143.0, 12324.876751232732, 12530.122891397516, 13090.0836941417, 12018.939905300418, 12027.699307517381, 12030.939845300718, 12351.3824308786, 12476.18904771187], "value": 7988.463293032954, "unit": "score"}], "name": "dromaeo_css", "value": 7470.848359201064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 112.0, 109.0, 111.0, 110.0, 108.0, 110.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 129.0, 169.0, 123.0, 126.0, 156.0, 131.0, 167.0, 124.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 162.0, 163.0, 160.0, 161.0, 160.0, 160.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 109.0, 76.0, 75.0, 73.0, 74.0, 105.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 111.0, 85.0, 83.0, 86.0, 116.0, 85.0, 85.0, 125.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 145.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 51.0, 50.0, 52.0, 52.0, 50.0, 53.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 77.0, 76.0, 76.0, 77.0, 81.0, 82.0, 106.0, 78.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 127.0, 117.0, 125.0, 115.0, 122.0, 115.0, 132.0, 121.0, 121.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 153.0, 152.0, 152.0, 152.0, 153.0, 156.0, 158.0, 150.0, 150.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 62.0, 62.0, 60.0, 66.0, 61.0, 61.0, 63.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1475.5, "alertThreshold": 2.0}]} 03:21:29 INFO - Return code: 0 03:21:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:29 ERROR - # TBPL SUCCESS # 03:21:29 INFO - Running post-action listener: _resource_record_post_action 03:21:29 INFO - ##### 03:21:29 INFO - ##### Finished run-tests step (success) 03:21:29 INFO - ##### 03:21:29 INFO - Running post-run listener: _resource_record_post_run 03:21:29 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 517107712; Read time: 8; Write time: 435472 03:21:29 INFO - TinderboxPrint: CPU usage
13.0% 03:21:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 03:21:29 INFO - TinderboxPrint: I/O write bytes / time
517,107,712 / 435,472 03:21:29 INFO - TinderboxPrint: CPU idle
8,251.3 (86.9%) 03:21:29 INFO - TinderboxPrint: CPU user
1,195.8 (12.6%) 03:21:29 INFO - TinderboxPrint: Swap in / out
0 / 0 03:21:29 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 262586368; Read time: 0; Write time: 254732 03:21:29 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254357504; Read time: 8; Write time: 180720 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:30 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:34 INFO - (blobuploader) - INFO - Done attempting. 03:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:34 INFO - (blobuploader) - INFO - Done attempting. 03:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: 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 - Iteration through files over. 03:21:35 INFO - Return code: 0 03:21:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9bbe4877f5932dfe1a82ff8086a786cc8a5b88aa864b47a6d6fb441222415e19d6064ac21a2c0d02b5d6b968a480e08bc27c8ed10c75815ebbd062326af0f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58c574654c7b03c9cb97c7daf42aa5281d3398e679814f97bec7f11b4fb68c2c1081108296d9225b4397fabeafbf825f601f579bcb3849b06ecf5d8e6aec2f90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96f313f686d542033a4e854dc5d3d8405c4e122e2a9c0dfd457bcdf689a2454e07654f47054f4119602ed7bff3aaf328ecc9dd42d414249401e3bd3f1a0574c8"} 03:21:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:35 INFO - Contents: 03:21:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9bbe4877f5932dfe1a82ff8086a786cc8a5b88aa864b47a6d6fb441222415e19d6064ac21a2c0d02b5d6b968a480e08bc27c8ed10c75815ebbd062326af0f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58c574654c7b03c9cb97c7daf42aa5281d3398e679814f97bec7f11b4fb68c2c1081108296d9225b4397fabeafbf825f601f579bcb3849b06ecf5d8e6aec2f90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96f313f686d542033a4e854dc5d3d8405c4e122e2a9c0dfd457bcdf689a2454e07654f47054f4119602ed7bff3aaf328ecc9dd42d414249401e3bd3f1a0574c8"} 03:21:35 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:35 INFO - Copying logs to upload dir... 03:21:35 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.711472 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-30 03:21:35.182763) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:35.186751) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716 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/d9bbe4877f5932dfe1a82ff8086a786cc8a5b88aa864b47a6d6fb441222415e19d6064ac21a2c0d02b5d6b968a480e08bc27c8ed10c75815ebbd062326af0f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58c574654c7b03c9cb97c7daf42aa5281d3398e679814f97bec7f11b4fb68c2c1081108296d9225b4397fabeafbf825f601f579bcb3849b06ecf5d8e6aec2f90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96f313f686d542033a4e854dc5d3d8405c4e122e2a9c0dfd457bcdf689a2454e07654f47054f4119602ed7bff3aaf328ecc9dd42d414249401e3bd3f1a0574c8"} 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.011271 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/d9bbe4877f5932dfe1a82ff8086a786cc8a5b88aa864b47a6d6fb441222415e19d6064ac21a2c0d02b5d6b968a480e08bc27c8ed10c75815ebbd062326af0f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58c574654c7b03c9cb97c7daf42aa5281d3398e679814f97bec7f11b4fb68c2c1081108296d9225b4397fabeafbf825f601f579bcb3849b06ecf5d8e6aec2f90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/96f313f686d542033a4e854dc5d3d8405c4e122e2a9c0dfd457bcdf689a2454e07654f47054f4119602ed7bff3aaf328ecc9dd42d414249401e3bd3f1a0574c8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:35.212226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:35.212539) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1469861423.555525-1527322716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:21:35.294983) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:21:35.295309) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:21:35.295605) ========= ========= Total master_lag: 0.29 =========