builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-056 starttime: 1468076400.85 results: success (0) buildid: 20160709052725 builduid: ff6865b4b74d4f828e1a85c5930b7a80 revision: d46777a162f52b80bbc9b94b0b9a573f27e3d0da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:00.848486) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:00.849141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:00.849433) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:00.933874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:00.934142) ========= 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=52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-09 08:00: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 71.0M=0s 2016-07-09 08:00:01 (71.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141935 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:01.129862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:01.130164) ========= 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=52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017029 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:01.182350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:01.182711) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d46777a162f52b80bbc9b94b0b9a573f27e3d0da --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 d46777a162f52b80bbc9b94b0b9a573f27e3d0da --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=52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-09 08:00:01,468 truncating revision to first 12 chars 2016-07-09 08:00:01,468 Setting DEBUG logging. 2016-07-09 08:00:01,469 attempt 1/10 2016-07-09 08:00:01,469 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d46777a162f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-09 08:00:01,818 unpacking tar archive at: mozilla-aurora-d46777a162f5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686598 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:02.068985) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:02.069596) ========= script_repo_revision: d46777a162f52b80bbc9b94b0b9a573f27e3d0da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:02.070273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:02.076916) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 08:00:02.357573) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-09 08:00:02.357888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:00:02 INFO - MultiFileLogger online at 20160709 08:00:02 in /builds/slave/test 08:00:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:00:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:00:02 INFO - {'append_to_log': False, 08:00:02 INFO - 'base_work_dir': '/builds/slave/test', 08:00:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:00:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:00:02 INFO - 'branch': 'Mozilla-Aurora', 08:00:02 INFO - 'buildbot_json_path': 'buildprops.json', 08:00:02 INFO - 'config_files': ('talos/linux_config.py',), 08:00:02 INFO - 'default_actions': ('clobber', 08:00:02 INFO - 'read-buildbot-config', 08:00:02 INFO - 'download-and-extract', 08:00:02 INFO - 'populate-webroot', 08:00:02 INFO - 'create-virtualenv', 08:00:02 INFO - 'install', 08:00:02 INFO - 'run-tests'), 08:00:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:00:02 INFO - 'download_minidump_stackwalk': True, 08:00:02 INFO - 'download_symbols': 'ondemand', 08:00:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:00:02 INFO - 'tooltool.py': '/tools/tooltool.py', 08:00:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:00:02 INFO - '/tools/misc-python/virtualenv.py')}, 08:00:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:00:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:00:02 INFO - 'installer_path': 'installer.exe', 08:00:02 INFO - 'log_level': 'info', 08:00:02 INFO - 'log_name': 'talos', 08:00:02 INFO - 'log_to_console': True, 08:00:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:00:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:00:02 INFO - 'opt_config_files': (), 08:00:02 INFO - 'pip_index': False, 08:00:02 INFO - 'sps_profile': False, 08:00:02 INFO - 'sps_profile_interval': 0, 08:00:02 INFO - 'suite': 'dromaeojs-e10s', 08:00:02 INFO - 'system_bits': '32', 08:00:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:00:02 INFO - 'title': 'talos-linux64-ix-056', 08:00:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:00:02 INFO - 'use_talos_json': True, 08:00:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:00:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:00:02 INFO - 'work_dir': 'build'} 08:00:02 INFO - ##### 08:00:02 INFO - ##### Running clobber step. 08:00:02 INFO - ##### 08:00:02 INFO - Running pre-action listener: _resource_record_pre_action 08:00:02 INFO - Running main action method: clobber 08:00:02 INFO - rmtree: /builds/slave/test/build 08:00:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:00:03 INFO - Running post-action listener: _resource_record_post_action 08:00:03 INFO - ##### 08:00:03 INFO - ##### Finished clobber step (success) 08:00:03 INFO - ##### 08:00:03 INFO - ##### 08:00:03 INFO - ##### Running read-buildbot-config step. 08:00:03 INFO - ##### 08:00:03 INFO - Running pre-action listener: _resource_record_pre_action 08:00:03 INFO - Running main action method: read_buildbot_config 08:00:03 INFO - Using buildbot properties: 08:00:03 INFO - { 08:00:03 INFO - "project": "", 08:00:03 INFO - "product": "firefox", 08:00:03 INFO - "script_repo_revision": "production", 08:00:03 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 08:00:03 INFO - "repository": "", 08:00:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 08:00:03 INFO - "buildid": "20160709052725", 08:00:03 INFO - "pgo_build": "True", 08:00:03 INFO - "basedir": "/builds/slave/test", 08:00:03 INFO - "buildnumber": 410, 08:00:03 INFO - "slavename": "talos-linux64-ix-056", 08:00:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:00:03 INFO - "platform": "ubuntu64_hw", 08:00:03 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:00:03 INFO - "branch": "mozilla-aurora", 08:00:03 INFO - "repo_path": "releases/mozilla-aurora", 08:00:03 INFO - "revision": "d46777a162f52b80bbc9b94b0b9a573f27e3d0da", 08:00:03 INFO - "stage_platform": "linux64-pgo", 08:00:03 INFO - "builduid": "ff6865b4b74d4f828e1a85c5930b7a80", 08:00:03 INFO - "slavebuilddir": "test" 08:00:03 INFO - } 08:00:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 08:00:03 INFO - Running post-action listener: _resource_record_post_action 08:00:03 INFO - ##### 08:00:03 INFO - ##### Finished read-buildbot-config step (success) 08:00:03 INFO - ##### 08:00:03 INFO - ##### 08:00:03 INFO - ##### Running download-and-extract step. 08:00:03 INFO - ##### 08:00:03 INFO - Running pre-action listener: _resource_record_pre_action 08:00:03 INFO - Running main action method: download_and_extract 08:00:03 INFO - mkdir: /builds/slave/test/build/tests 08:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:03 INFO - https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:00:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:00:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:00:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:00:06 INFO - Downloaded 1361 bytes. 08:00:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:00:06 INFO - Using the following test package requirements: 08:00:06 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:00:06 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:00:06 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:00:06 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'jsshell-linux-x86_64.zip'], 08:00:06 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:00:06 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:00:06 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:00:06 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:00:06 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:00:06 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:00:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:00:06 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:06 INFO - https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:00:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:00:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:00:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:00:10 INFO - Downloaded 24525486 bytes. 08:00: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'] 08:00: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 08:00:11 INFO - Return code: 0 08:00:11 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:11 INFO - https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:00:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:00:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:00:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:00:27 INFO - Downloaded 24525486 bytes. 08:00:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:00:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:00:28 INFO - Return code: 0 08:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:28 INFO - https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:00:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:00:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:00:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:00:29 INFO - Downloaded 10833369 bytes. 08:00:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:00:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:00:30 INFO - Return code: 0 08:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:30 INFO - https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:00:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:00:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:00:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:00:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:00:31 INFO - Downloaded 63642174 bytes. 08:00:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:00:31 INFO - mkdir: /builds/slave/test/properties 08:00:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:00:31 INFO - Writing to file /builds/slave/test/properties/build_url 08:00:31 INFO - Contents: 08:00:31 INFO - build_url:https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:00:32 INFO - Running post-action listener: _resource_record_post_action 08:00:32 INFO - Running post-action listener: set_extra_try_arguments 08:00:32 INFO - ##### 08:00:32 INFO - ##### Finished download-and-extract step (success) 08:00:32 INFO - ##### 08:00:32 INFO - ##### 08:00:32 INFO - ##### Running populate-webroot step. 08:00:32 INFO - ##### 08:00:32 INFO - Running pre-action listener: _resource_record_pre_action 08:00:32 INFO - Running main action method: populate_webroot 08:00:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:00:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:00:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:00:32 INFO - '--tppagecycles', 08:00:32 INFO - '7'], 08:00:32 INFO - 'tests': ['tsvgm']}}, 08:00:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:00:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:00:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:00:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:00:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['damp', 'tps']}, 08:00:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'tests': ['damp', 'tps']}, 08:00:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['dromaeo_dom']}, 08:00:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:00:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['a11yr', 08:00:32 INFO - 'ts_paint', 08:00:32 INFO - 'tpaint', 08:00:32 INFO - 'sessionrestore', 08:00:32 INFO - 'sessionrestore_no_auto_restore', 08:00:32 INFO - 'tabpaint']}, 08:00:32 INFO - 'other-e10s': {'tests': ['a11yr', 08:00:32 INFO - 'ts_paint', 08:00:32 INFO - 'tpaint', 08:00:32 INFO - 'sessionrestore', 08:00:32 INFO - 'sessionrestore_no_auto_restore', 08:00:32 INFO - 'tabpaint']}, 08:00:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:00:32 INFO - 'ts_paint', 08:00:32 INFO - 'tpaint', 08:00:32 INFO - 'sessionrestore', 08:00:32 INFO - 'sessionrestore_no_auto_restore', 08:00:32 INFO - 'tabpaint']}, 08:00:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:00:32 INFO - 'ts_paint', 08:00:32 INFO - 'tpaint', 08:00:32 INFO - 'sessionrestore', 08:00:32 INFO - 'sessionrestore_no_auto_restore', 08:00:32 INFO - 'tabpaint']}, 08:00:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['a11yr', 08:00:32 INFO - 'ts_paint', 08:00:32 INFO - 'tpaint', 08:00:32 INFO - 'sessionrestore', 08:00:32 INFO - 'sessionrestore_no_auto_restore', 08:00:32 INFO - 'tabpaint']}, 08:00:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['a11yr', 08:00:32 INFO - 'ts_paint', 08:00:32 INFO - 'tpaint', 08:00:32 INFO - 'sessionrestore', 08:00:32 INFO - 'sessionrestore_no_auto_restore', 08:00:32 INFO - 'tabpaint']}, 08:00:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['tsvgx', 08:00:32 INFO - 'tsvgr_opacity', 08:00:32 INFO - 'tart', 08:00:32 INFO - 'tscrollx', 08:00:32 INFO - 'cart']}, 08:00:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:00:32 INFO - 'tsvgr_opacity', 08:00:32 INFO - 'tart', 08:00:32 INFO - 'tscrollx', 08:00:32 INFO - 'cart']}, 08:00:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'talos_options': ['--disable-e10s'], 08:00:32 INFO - 'tests': ['tp5o']}, 08:00:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'tests': ['tp5o']}, 08:00:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'talos_options': ['--disable-e10s', 08:00:32 INFO - '--xperf_path', 08:00:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:32 INFO - 'tests': ['tp5n']}, 08:00:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:32 INFO - 'talos_options': ['--xperf_path', 08:00:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:32 INFO - 'tests': ['tp5n']}}, 08:00:32 INFO - 'talos.zip': {'path': '', 08:00:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:00:32 INFO - Running post-action listener: _resource_record_post_action 08:00:32 INFO - ##### 08:00:32 INFO - ##### Finished populate-webroot step (success) 08:00:32 INFO - ##### 08:00:32 INFO - ##### 08:00:32 INFO - ##### Running create-virtualenv step. 08:00:32 INFO - ##### 08:00:32 INFO - Running pre-action listener: _resource_record_pre_action 08:00:32 INFO - Running main action method: create_virtualenv 08:00:32 INFO - Creating virtualenv /builds/slave/test/build/venv 08:00:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:00:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:00:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:00:32 INFO - Using real prefix '/usr' 08:00:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:00:33 INFO - Installing distribute.............................................................................................................................................................................................done. 08:00:35 INFO - Installing pip.................done. 08:00:35 INFO - Return code: 0 08:00:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:35 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:00:35 INFO - Using env: {'DISPLAY': ':0', 08:00:35 INFO - 'HOME': '/home/cltbld', 08:00:35 INFO - 'LANG': 'en_US.UTF-8', 08:00:35 INFO - 'LANGUAGE': 'en_US:en', 08:00:35 INFO - 'LOGNAME': 'cltbld', 08:00:35 INFO - 'MAIL': '/var/mail/cltbld', 08:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:35 INFO - 'MOZ_NO_REMOTE': '1', 08:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:35 INFO - 'NO_EM_RESTART': '1', 08:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:35 INFO - 'PWD': '/builds/slave/test', 08:00:35 INFO - 'SHELL': '/bin/bash', 08:00:35 INFO - 'SHLVL': '1', 08:00:35 INFO - 'TERM': 'linux', 08:00:35 INFO - 'TMOUT': '86400', 08:00:35 INFO - 'USER': 'cltbld', 08:00:35 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:35 INFO - '_': '/tools/buildbot/bin/python'} 08:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:00:36 INFO - Cleaning up... 08:00:36 INFO - Return code: 0 08:00:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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', '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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:00: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 psutil>=3.1.1 08:00:36 INFO - Using env: {'DISPLAY': ':0', 08:00:36 INFO - 'HOME': '/home/cltbld', 08:00:36 INFO - 'LANG': 'en_US.UTF-8', 08:00:36 INFO - 'LANGUAGE': 'en_US:en', 08:00:36 INFO - 'LOGNAME': 'cltbld', 08:00:36 INFO - 'MAIL': '/var/mail/cltbld', 08:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:36 INFO - 'MOZ_NO_REMOTE': '1', 08:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:36 INFO - 'NO_EM_RESTART': '1', 08:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:36 INFO - 'PWD': '/builds/slave/test', 08:00:36 INFO - 'SHELL': '/bin/bash', 08:00:36 INFO - 'SHLVL': '1', 08:00:36 INFO - 'TERM': 'linux', 08:00:36 INFO - 'TMOUT': '86400', 08:00:36 INFO - 'USER': 'cltbld', 08:00:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:36 INFO - '_': '/tools/buildbot/bin/python'} 08:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:36 INFO - Downloading/unpacking psutil>=3.1.1 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:38 INFO - Installing collected packages: psutil 08:00:38 INFO - Running setup.py install for psutil 08:00:38 INFO - building 'psutil._psutil_linux' extension 08:00:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:00:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:00:41 INFO - building 'psutil._psutil_posix' extension 08:00:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:00:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:00:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:41 INFO - Successfully installed psutil 08:00:41 INFO - Cleaning up... 08:00:41 INFO - Return code: 0 08:00:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:41 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:00:41 INFO - Using env: {'DISPLAY': ':0', 08:00:41 INFO - 'HOME': '/home/cltbld', 08:00:41 INFO - 'LANG': 'en_US.UTF-8', 08:00:41 INFO - 'LANGUAGE': 'en_US:en', 08:00:41 INFO - 'LOGNAME': 'cltbld', 08:00:41 INFO - 'MAIL': '/var/mail/cltbld', 08:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:41 INFO - 'MOZ_NO_REMOTE': '1', 08:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:41 INFO - 'NO_EM_RESTART': '1', 08:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:41 INFO - 'PWD': '/builds/slave/test', 08:00:41 INFO - 'SHELL': '/bin/bash', 08:00:41 INFO - 'SHLVL': '1', 08:00:41 INFO - 'TERM': 'linux', 08:00:41 INFO - 'TMOUT': '86400', 08:00:41 INFO - 'USER': 'cltbld', 08:00:41 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:41 INFO - '_': '/tools/buildbot/bin/python'} 08:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:00:43 INFO - Installing collected packages: mozsystemmonitor 08:00:43 INFO - Running setup.py install for mozsystemmonitor 08:00:43 INFO - Successfully installed mozsystemmonitor 08:00:43 INFO - Cleaning up... 08:00:43 INFO - Return code: 0 08:00:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:43 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:00:43 INFO - Using env: {'DISPLAY': ':0', 08:00:43 INFO - 'HOME': '/home/cltbld', 08:00:43 INFO - 'LANG': 'en_US.UTF-8', 08:00:43 INFO - 'LANGUAGE': 'en_US:en', 08:00:43 INFO - 'LOGNAME': 'cltbld', 08:00:43 INFO - 'MAIL': '/var/mail/cltbld', 08:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:43 INFO - 'MOZ_NO_REMOTE': '1', 08:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:43 INFO - 'NO_EM_RESTART': '1', 08:00:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:43 INFO - 'PWD': '/builds/slave/test', 08:00:43 INFO - 'SHELL': '/bin/bash', 08:00:43 INFO - 'SHLVL': '1', 08:00:43 INFO - 'TERM': 'linux', 08:00:43 INFO - 'TMOUT': '86400', 08:00:43 INFO - 'USER': 'cltbld', 08:00:43 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:43 INFO - '_': '/tools/buildbot/bin/python'} 08:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:46 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:46 INFO - warning: no files found matching '*.txt' 08:00:46 INFO - no previously-included directories found matching 'build' 08:00:46 INFO - no previously-included directories found matching 'dist' 08:00:46 INFO - no previously-included directories found matching '.git*' 08:00:46 INFO - Installing collected packages: jsonschema, functools32 08:00:46 INFO - Running setup.py install for functools32 08:00:46 INFO - warning: no files found matching '*.txt' 08:00:46 INFO - no previously-included directories found matching 'build' 08:00:46 INFO - no previously-included directories found matching 'dist' 08:00:46 INFO - no previously-included directories found matching '.git*' 08:00:46 INFO - Successfully installed jsonschema functools32 08:00:46 INFO - Cleaning up... 08:00:46 INFO - Return code: 0 08:00:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:46 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:00:46 INFO - Using env: {'DISPLAY': ':0', 08:00:46 INFO - 'HOME': '/home/cltbld', 08:00:46 INFO - 'LANG': 'en_US.UTF-8', 08:00:46 INFO - 'LANGUAGE': 'en_US:en', 08:00:46 INFO - 'LOGNAME': 'cltbld', 08:00:46 INFO - 'MAIL': '/var/mail/cltbld', 08:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:46 INFO - 'MOZ_NO_REMOTE': '1', 08:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:46 INFO - 'NO_EM_RESTART': '1', 08:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:46 INFO - 'PWD': '/builds/slave/test', 08:00:46 INFO - 'SHELL': '/bin/bash', 08:00:46 INFO - 'SHLVL': '1', 08:00:46 INFO - 'TERM': 'linux', 08:00:46 INFO - 'TMOUT': '86400', 08:00:46 INFO - 'USER': 'cltbld', 08:00:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:46 INFO - '_': '/tools/buildbot/bin/python'} 08:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:00:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:49 INFO - Downloading docopt-0.6.1.tar.gz 08:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:49 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:49 INFO - Running setup.py install for blobuploader 08:00:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:00:49 INFO - Running setup.py install for requests 08:00:49 INFO - Running setup.py install for docopt 08:00:49 INFO - Successfully installed blobuploader requests docopt 08:00:49 INFO - Cleaning up... 08:00:49 INFO - Return code: 0 08:00:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:49 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:00:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:49 INFO - Using env: {'DISPLAY': ':0', 08:00:49 INFO - 'HOME': '/home/cltbld', 08:00:49 INFO - 'LANG': 'en_US.UTF-8', 08:00:49 INFO - 'LANGUAGE': 'en_US:en', 08:00:49 INFO - 'LOGNAME': 'cltbld', 08:00:49 INFO - 'MAIL': '/var/mail/cltbld', 08:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:49 INFO - 'MOZ_NO_REMOTE': '1', 08:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:49 INFO - 'NO_EM_RESTART': '1', 08:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:49 INFO - 'PWD': '/builds/slave/test', 08:00:49 INFO - 'SHELL': '/bin/bash', 08:00:49 INFO - 'SHLVL': '1', 08:00:49 INFO - 'TERM': 'linux', 08:00:49 INFO - 'TMOUT': '86400', 08:00:49 INFO - 'USER': 'cltbld', 08:00:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:49 INFO - '_': '/tools/buildbot/bin/python'} 08:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:50 INFO - Running setup.py (path:/tmp/pip-hyzBIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:50 INFO - Running setup.py (path:/tmp/pip-95wSV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:50 INFO - Running setup.py (path:/tmp/pip-KuX34N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:50 INFO - Running setup.py (path:/tmp/pip-5cz2Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:50 INFO - Running setup.py (path:/tmp/pip-HB2cfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:50 INFO - Running setup.py (path:/tmp/pip-4MUtme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:50 INFO - Running setup.py (path:/tmp/pip-BRHwDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:50 INFO - Running setup.py (path:/tmp/pip-vhIf4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:50 INFO - Running setup.py (path:/tmp/pip-iGQbS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:50 INFO - Running setup.py (path:/tmp/pip-5Pp2Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:50 INFO - Running setup.py (path:/tmp/pip-d9hfcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:50 INFO - Running setup.py (path:/tmp/pip-SfMUDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:50 INFO - Running setup.py (path:/tmp/pip-74_CWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:50 INFO - Running setup.py (path:/tmp/pip-ebXOZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:51 INFO - Running setup.py (path:/tmp/pip-YqjqXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:51 INFO - Running setup.py (path:/tmp/pip-ei8EWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:51 INFO - Running setup.py (path:/tmp/pip-CRF8gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:51 INFO - Running setup.py install for manifestparser 08:00:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Running setup.py install for mozcrash 08:00:51 INFO - Running setup.py install for mozdebug 08:00:51 INFO - Running setup.py install for mozdevice 08:00:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Running setup.py install for mozfile 08:00:51 INFO - Running setup.py install for mozhttpd 08:00:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Running setup.py install for mozinfo 08:00:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Running setup.py install for mozInstall 08:00:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozleak 08:00:52 INFO - Running setup.py install for mozlog 08:00:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for moznetwork 08:00:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozprocess 08:00:52 INFO - Running setup.py install for mozprofile 08:00:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozrunner 08:00:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozscreenshot 08:00:52 INFO - Running setup.py install for moztest 08:00:52 INFO - Running setup.py install for mozversion 08:00:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:53 INFO - Cleaning up... 08:00:53 INFO - Return code: 0 08:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:53 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:53 INFO - Using env: {'DISPLAY': ':0', 08:00:53 INFO - 'HOME': '/home/cltbld', 08:00:53 INFO - 'LANG': 'en_US.UTF-8', 08:00:53 INFO - 'LANGUAGE': 'en_US:en', 08:00:53 INFO - 'LOGNAME': 'cltbld', 08:00:53 INFO - 'MAIL': '/var/mail/cltbld', 08:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:53 INFO - 'MOZ_NO_REMOTE': '1', 08:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:53 INFO - 'NO_EM_RESTART': '1', 08:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:53 INFO - 'PWD': '/builds/slave/test', 08:00:53 INFO - 'SHELL': '/bin/bash', 08:00:53 INFO - 'SHLVL': '1', 08:00:53 INFO - 'TERM': 'linux', 08:00:53 INFO - 'TMOUT': '86400', 08:00:53 INFO - 'USER': 'cltbld', 08:00:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:53 INFO - '_': '/tools/buildbot/bin/python'} 08:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:53 INFO - Running setup.py (path:/tmp/pip-EeW9Mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:53 INFO - Running setup.py (path:/tmp/pip-FTJmWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:53 INFO - Running setup.py (path:/tmp/pip-FffCfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:53 INFO - Running setup.py (path:/tmp/pip-PaPf1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:53 INFO - Running setup.py (path:/tmp/pip-rzrVGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:53 INFO - Running setup.py (path:/tmp/pip-zBnmdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:53 INFO - Running setup.py (path:/tmp/pip-gbi8Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:53 INFO - Running setup.py (path:/tmp/pip-4Zg34Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:53 INFO - Running setup.py (path:/tmp/pip-sAXZmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:53 INFO - Running setup.py (path:/tmp/pip-t4saiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:54 INFO - Running setup.py (path:/tmp/pip-bo33OS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:54 INFO - Running setup.py (path:/tmp/pip-BRW2BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:54 INFO - Running setup.py (path:/tmp/pip-movDi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:54 INFO - Running setup.py (path:/tmp/pip-OCXeQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:54 INFO - Running setup.py (path:/tmp/pip-zN0KhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:54 INFO - Running setup.py (path:/tmp/pip-VsuZGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:54 INFO - Running setup.py (path:/tmp/pip-FI41re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:56 INFO - Downloading blessings-1.6.tar.gz 08:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:56 INFO - Installing collected packages: blessings 08:00:56 INFO - Running setup.py install for blessings 08:00:56 INFO - Successfully installed blessings 08:00:56 INFO - Cleaning up... 08:00:56 INFO - Return code: 0 08:00:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:00:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:57 INFO - Reading from file tmpfile_stdout 08:00:57 INFO - Current package versions: 08:00:57 INFO - argparse == 1.2.1 08:00:57 INFO - blessings == 1.6 08:00:57 INFO - blobuploader == 1.2.4 08:00:57 INFO - docopt == 0.6.1 08:00:57 INFO - functools32 == 3.2.3-2 08:00:57 INFO - jsonschema == 2.5.1 08:00:57 INFO - manifestparser == 1.1 08:00:57 INFO - mozInstall == 1.12 08:00:57 INFO - mozcrash == 0.17 08:00:57 INFO - mozdebug == 0.1 08:00:57 INFO - mozdevice == 0.48 08:00:57 INFO - mozfile == 1.2 08:00:57 INFO - mozhttpd == 0.7 08:00:57 INFO - mozinfo == 0.9 08:00:57 INFO - mozleak == 0.1 08:00:57 INFO - mozlog == 3.2 08:00:57 INFO - moznetwork == 0.27 08:00:57 INFO - mozprocess == 0.22 08:00:57 INFO - mozprofile == 0.28 08:00:57 INFO - mozrunner == 6.11 08:00:57 INFO - mozscreenshot == 0.1 08:00:57 INFO - mozsystemmonitor == 0.3 08:00:57 INFO - moztest == 0.7 08:00:57 INFO - mozversion == 1.4 08:00:57 INFO - psutil == 3.1.1 08:00:57 INFO - requests == 1.2.3 08:00:57 INFO - wsgiref == 0.1.2 08:00:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:57 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 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:57 INFO - Using env: {'DISPLAY': ':0', 08:00:57 INFO - 'HOME': '/home/cltbld', 08:00:57 INFO - 'LANG': 'en_US.UTF-8', 08:00:57 INFO - 'LANGUAGE': 'en_US:en', 08:00:57 INFO - 'LOGNAME': 'cltbld', 08:00:57 INFO - 'MAIL': '/var/mail/cltbld', 08:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:57 INFO - 'MOZ_NO_REMOTE': '1', 08:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:57 INFO - 'NO_EM_RESTART': '1', 08:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:57 INFO - 'PWD': '/builds/slave/test', 08:00:57 INFO - 'SHELL': '/bin/bash', 08:00:57 INFO - 'SHLVL': '1', 08:00:57 INFO - 'TERM': 'linux', 08:00:57 INFO - 'TMOUT': '86400', 08:00:57 INFO - 'USER': 'cltbld', 08:00:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:57 INFO - '_': '/tools/buildbot/bin/python'} 08:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:00:57 INFO - Cleaning up... 08:00:57 INFO - Return code: 0 08:00:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:00:57 INFO - Using env: {'DISPLAY': ':0', 08:00:57 INFO - 'HOME': '/home/cltbld', 08:00:57 INFO - 'LANG': 'en_US.UTF-8', 08:00:57 INFO - 'LANGUAGE': 'en_US:en', 08:00:57 INFO - 'LOGNAME': 'cltbld', 08:00:57 INFO - 'MAIL': '/var/mail/cltbld', 08:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:57 INFO - 'MOZ_NO_REMOTE': '1', 08:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:57 INFO - 'NO_EM_RESTART': '1', 08:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:57 INFO - 'PWD': '/builds/slave/test', 08:00:57 INFO - 'SHELL': '/bin/bash', 08:00:57 INFO - 'SHLVL': '1', 08:00:57 INFO - 'TERM': 'linux', 08:00:57 INFO - 'TMOUT': '86400', 08:00:57 INFO - 'USER': 'cltbld', 08:00:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:57 INFO - '_': '/tools/buildbot/bin/python'} 08:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:00:57 INFO - Cleaning up... 08:00:57 INFO - Return code: 0 08:00:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ddf150f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, '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': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:00:57 INFO - Using env: {'DISPLAY': ':0', 08:00:57 INFO - 'HOME': '/home/cltbld', 08:00:57 INFO - 'LANG': 'en_US.UTF-8', 08:00:57 INFO - 'LANGUAGE': 'en_US:en', 08:00:57 INFO - 'LOGNAME': 'cltbld', 08:00:57 INFO - 'MAIL': '/var/mail/cltbld', 08:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:57 INFO - 'MOZ_NO_REMOTE': '1', 08:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:57 INFO - 'NO_EM_RESTART': '1', 08:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:57 INFO - 'PWD': '/builds/slave/test', 08:00:57 INFO - 'SHELL': '/bin/bash', 08:00:57 INFO - 'SHLVL': '1', 08:00:57 INFO - 'TERM': 'linux', 08:00:57 INFO - 'TMOUT': '86400', 08:00:57 INFO - 'USER': 'cltbld', 08:00:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:57 INFO - '_': '/tools/buildbot/bin/python'} 08:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:57 INFO - Downloading/unpacking flake8 08:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:00:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:00:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:01:00 INFO - no previously-included directories found matching 'docs/_build' 08:01:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:01:00 INFO - Running setup.py install for pep8 08:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:01:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:01:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:01:00 INFO - no previously-included directories found matching 'docs/_build' 08:01:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:01:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:01:00 INFO - Cleaning up... 08:01:00 INFO - Return code: 0 08:01:00 INFO - Running post-action listener: _resource_record_post_action 08:01:00 INFO - Running post-action listener: _start_resource_monitoring 08:01:00 INFO - Starting resource monitoring. 08:01:00 INFO - ##### 08:01:00 INFO - ##### Finished create-virtualenv step (success) 08:01:00 INFO - ##### 08:01:00 INFO - ##### 08:01:00 INFO - ##### Running install step. 08:01:00 INFO - ##### 08:01:00 INFO - Running pre-action listener: _resource_record_pre_action 08:01:00 INFO - Running main action method: install 08:01:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:01:00 INFO - Reading from file tmpfile_stdout 08:01:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:01:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:01:00 INFO - Reading from file tmpfile_stdout 08:01:00 INFO - Output received: 08:01:00 INFO - Usage: mozinstall [options] installer 08:01:00 INFO - Options: 08:01:00 INFO - -h, --help show this help message and exit 08:01:00 INFO - -d DEST, --destination=DEST 08:01:00 INFO - Directory to install application into. [default: 08:01:00 INFO - "/builds/slave/test"] 08:01:00 INFO - --app=APP Application being installed. [default: firefox] 08:01:00 INFO - mkdir: /builds/slave/test/build/application 08:01:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:01:16 INFO - Reading from file tmpfile_stdout 08:01:16 INFO - Output received: 08:01:16 INFO - /builds/slave/test/build/application/firefox/firefox 08:01:16 INFO - Running post-action listener: _resource_record_post_action 08:01:16 INFO - ##### 08:01:16 INFO - ##### Finished install step (success) 08:01:16 INFO - ##### 08:01:16 INFO - ##### 08:01:16 INFO - ##### Running run-tests step. 08:01:16 INFO - ##### 08:01:16 INFO - Running pre-action listener: _resource_record_pre_action 08:01:16 INFO - Running main action method: run_tests 08:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:01:16 INFO - Python 2.7.3 08:01:16 INFO - Return code: 0 08:01:16 INFO - grabbing minidump binary from tooltool 08:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:16 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 0x15ca1c0>, '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 0x15cb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15cb550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:01:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:01:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:01:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:01:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:01:17 INFO - Return code: 0 08:01:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:01:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:01:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:01:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:01:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:01:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:01:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:01:18 INFO - Return code: 0 08:01:18 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:01:18 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:01:18 INFO - Using env: {'DISPLAY': ':0', 08:01:18 INFO - 'HOME': '/home/cltbld', 08:01:18 INFO - 'LANG': 'en_US.UTF-8', 08:01:18 INFO - 'LANGUAGE': 'en_US:en', 08:01:18 INFO - 'LOGNAME': 'cltbld', 08:01:18 INFO - 'MAIL': '/var/mail/cltbld', 08:01:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:01:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:18 INFO - 'MOZ_NO_REMOTE': '1', 08:01:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:18 INFO - 'NO_EM_RESTART': '1', 08:01:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:18 INFO - 'PWD': '/builds/slave/test', 08:01:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:01:18 INFO - 'SHELL': '/bin/bash', 08:01:18 INFO - 'SHLVL': '1', 08:01:18 INFO - 'TERM': 'linux', 08:01:18 INFO - 'TMOUT': '86400', 08:01:18 INFO - 'USER': 'cltbld', 08:01:18 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331', 08:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:18 INFO - '_': '/tools/buildbot/bin/python'} 08:01:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:01:18 INFO - mozversion application_buildid: 20160709052725 08:01:18 INFO - mozversion application_changeset: d46777a162f52b80bbc9b94b0b9a573f27e3d0da 08:01:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:01:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:01:18 INFO - mozversion application_name: Firefox 08:01:18 INFO - mozversion application_remotingname: firefox-dev 08:01:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:01:18 INFO - mozversion application_vendor: Mozilla 08:01:18 INFO - mozversion application_version: 49.0a2 08:01:18 INFO - mozversion platform_buildid: 20160709052725 08:01:18 INFO - mozversion platform_changeset: d46777a162f52b80bbc9b94b0b9a573f27e3d0da 08:01:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:01:18 INFO - mozversion platform_version: 49.0a2 08:01:18 INFO - using testdate: 1468076478 08:01:18 INFO - actual date: 1468076478 08:01:18 INFO - starting webserver on 'localhost:43945' 08:01:18 INFO - SUITE-START | Running 2 tests 08:01:18 INFO - TEST-START | dromaeo_css 08:01:18 INFO - operating with platform_type : linux_ 08:01:18 INFO - Initialising browser for dromaeo_css test... 08:01:18 INFO - TEST-INFO | started process 3331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHIdNT0/profile http://localhost:43945/getInfo.html) 08:01:24 INFO - PROCESS | 3331 | __metrics Screen width/height:1600/1200 08:01:24 INFO - PROCESS | 3331 | colorDepth:24 08:01:24 INFO - PROCESS | 3331 | Browser inner width/height: 1024/697 08:01:24 INFO - PROCESS | 3331 | __metrics 08:01:24 ERROR - PROCESS | 3331 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 08:01:25 INFO - TEST-INFO | 3331: exit 0 08:01:25 INFO - Browser initialized. 08:01:25 INFO - Running cycle 1/1 for dromaeo_css test... 08:01:25 INFO - TEST-INFO | started process 3460 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHIdNT0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:37 INFO - PROCESS | 3460 | RSS: Main: 155930624 08:01:37 INFO - PROCESS | 3460 | 08:02:27 INFO - PROCESS | 3460 | 1468076547113 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:04:35 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:43945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43945/tests/dromaeo/cssquery-ext.html) 08:04:36 INFO - PROCESS | 3460 | RSS: Main: 159436800 08:04:36 INFO - PROCESS | 3460 | 08:07:38 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:43945/tests/dromaeo/cssquery-ext.html (next: http://localhost:43945/tests/dromaeo/cssquery-jquery.html) 08:07:38 INFO - PROCESS | 3460 | RSS: Main: 157564928 08:07:38 INFO - PROCESS | 3460 | 08:10:40 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:43945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43945/tests/dromaeo/cssquery-mootools.html) 08:10:41 INFO - PROCESS | 3460 | RSS: Main: 156827648 08:10:41 INFO - PROCESS | 3460 | 08:13:43 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:43945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43945/tests/dromaeo/cssquery-prototype.html) 08:13:43 INFO - PROCESS | 3460 | RSS: Main: 156774400 08:13:43 INFO - PROCESS | 3460 | 08:16:46 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:43945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43945/tests/dromaeo/cssquery-yui.html) 08:16:46 INFO - PROCESS | 3460 | RSS: Main: 158875648 08:16:46 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | Cycle 1(1): loaded http://localhost:43945/tests/dromaeo/cssquery-yui.html (next: http://localhost:43945/tests/dromaeo/cssquery-dojo.html) 08:19:49 INFO - PROCESS | 3460 | RSS: Main: 158994432 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | __start_tp_report 08:19:49 INFO - PROCESS | 3460 | _x_x_mozilla_page_load 08:19:49 INFO - PROCESS | 3460 | _x_x_mozilla_page_load_details 08:19:49 INFO - PROCESS | 3460 | |i|pagename|runs| 08:19:49 INFO - PROCESS | 3460 | |0;dojo.html;3368.7305015598754;3385.6614338566137;3385.830708464576;3422.9731080675806;3429.3827111120004;5663.603547751659;5674.120511320742;5677.807660391312;5727.627704199224;5772.278465191851;5615.606907516475;5688.459596338341;5716.971415142919;5723.284589426322;5735.684537350444;5158.793648254065;5168.069747445458;5171.741412929338;5223.921641175385;5252.737363131847;5045.091883460976;5081.618878584102;5237.45006774289;5240.266362709223;5259.526642602165;5117.283580298761;5131.435542090386;5168.405633352175;5199.6360254782185;5253.317068781053;5194.71429071401;5198.71407072609;5228.111221092423;5233.842984710464;5249.766304918379;4995.625328100407;5070.138076526962;5150.948490515084;5193.740312984336;5208.817691380821;5583.776648934037;5593.328800543961;5615.887682246373;5663.348714897798;5672.858178545528;6188.319284878659;6188.628682279078;6216.129741836146;6218.409251121181;6252.406021427957;6037.094435834616;6175.382461753834;6291.653959032252;6303.495720342362;6327.398897104814;5762.510186634141;5854.18041474194;5867.589268751231;5890.087036509305;5937.406259374071;6407.167068281094;6417.647029413333;6432.453241474527;6433.613983161025;6439.774607888795;5879.147523609053;5945.256842894638;5974.790882319185;6093.268807743099;6105.877882442415;6157.630542167484;6162.168107305546;6162.291336496271;6167.259928808572;6168.25980882297;6004.909238474198;6075.058365953284;6093.421124993119;6096.512279017668;6142.662153581596;5915.822525324247;6051;6068.575199736064;6070.2412198475195;6099.359567245464;4657.510961349078;4672.2057250267535;4682.321063445781;4683.765811709401;4698.107359601665;4153.8961525962095;4167.83328666856;4175.958240417618;4190.245755763992;4226.36604509326;5776.624519406225;5970.880582388328;6004.039353703386;6036.728347224385;6093.756249750049;6118.724657390429;6131.96934015327;6161.075838624152;6213.5339849510565;6254.093156492283;5780.277465316835;6024.096385542204;6025.794841031811;6064.36324185963;6077.301110372276;5197.506236907488;5223.085959957069;5232.816851410183;5272.367315922037;5313.521783039545;6493.577917435352;6509.2839787623325;6526.902096468557;6537.34626537352;6544.149260596092;6090.543209259412;6122.448979591858;6279;6281.057841323746;6282.497400207881;6229.563930525001;6430.421262086435;6444.323346048597;6444.548881578245;6457.838554036187;5956.315023772322;6026.638401695912;6031.306399764083;6040.697965101639;6056.454919057306;6096.512279017579;6106.572539922163;6136.304638670978;6223.564350495421;6231.563790534619;6048.637081775108;6051.062085376773;6058.0610005449225;6082.9695851520455;6098.054801505774;6056.545759068175;6078.635281883101;6091.939080609137;6106.480949119375;6110;5803.854903627274;5884.823455296348;5897.64614123154;5951.642901425928;6014.518838492822;6077.817665470043;6091.725872335667;6127.571070025056;6129.632222066691;6164.4143806338325;5958.404159584007;5979.611325263844;6014.36849130835;6014.36849130835;6015.548833837567 08:19:49 INFO - PROCESS | 3460 | |1;ext.html;594.8631814682623;643.459494025019;645.3546453546451;664.292528457193;676.9559978601394;40462.79768601157;45595.544044559545;45675.54324456755;45722;45822;47116.29325560111;47489.525104748944;47731.28403073948;47750.52249477504;47917.28124078141;8690.304775617951;8807.559622018905;8840.469571825712;8884.644614215424;8941.748155258269;8867.157620026088;8883.11168883113;8890.86663700045;8911.68809091682;8967.372283940127;8990.460572365646;9013.647952807065;9019.639214431414;9031.864522032141;9047.185753282203;8917.242034427081;8934.463932164059;8964.417312874675;8971.192592666692;8988.865167022499;8868.600912958933;8880.289576833902;8909.732708018704;8960;8997.685081022199;43746.56253437488;44065.11869762619;44103.89740256487;44121;44182.55817441817;3871.9806400968096;4174.937375939363;4225.514065882401;4356.390105385249;4482.125985432842;34543.827280863436;35139;35338.29323413517;36308.455373169425;36324.81837590822;4504.234280172379;4613.331066995301;4631.513691062457;4693.413323334583;4876.975615121902;401476;401677;403097;404863.99999999703;405052;37911.81044094762;66570;67176.32823671798;67180.6640966792;67646.98529522061;39433.21133577312;41420.58579414227;41529.37705934414;41779;41799.37300940488;17193.570160745832;17231.65536689259;17355;17373.3050677974;17424.215910284067;13274.933625332005;13321.533746318832;13369.46522139123;13390.93304533471;13406.195628262336;9223.400478968979;9271.304652151115;9297.860532092023;9298.116678915492;9324.673636422829;3428.9314213715584;3524;3551.271989242251;3561.786292822387;3600.369935261319;1086.5110700184948;1093.3986307530827;1096.0354887698934;1194.3848917807336;1213.071999920062;34089.31821363559;34186;34243;34422.82788586041;34811;1198.4606926882764;1199.736058067224;1218.3055658274875;1234.6913271682079;1272.166730791333;17775.200115994812;18087.457376278733;18140.63718725618;18258.269669213347;18284.908575457303;648838;860612;866156;870090;879402;62449;62572.687136564935;62825.371746282864;62835.057474137924;63191;1468.7870258812413;1498.9475368362055;1509.9773503397457;1525.3517255166732;1528.335174199227;32493.512597310586;33086.83456582702;33305.33389332248;33328.33343333168;33735.49396759002;54461.72769136129;54823.451765481834;55181.17228241499;55224.17163742467;55266.72366638141;409881;415701;415976;418271;419818;30253.243668908453;30327;30570.847145764128;31090.22274443157;31663.683363166074;53703.73148134234;53965.73017134889;54314;54620.90758184894;55340.72329638326;45275.773621133005;45484.09031819411;45932;46846.29730553976;47399.289010665554;45010;46001.76999114983;46141;46500.534994650974;47004;45176.32235516404;46300.7684961573;46354.30468543042;46675.29987050265;48989;38493;39067.41398879076;39204.803975979936;40032.599674002886;40085.1982960345;29695.40609187761;30632.234194145323;30858;31017.68982310148;31198.37603247877 08:19:49 INFO - PROCESS | 3460 | |2;jquery.html;405.7829061452124;409.77871949147465;412.56062293657243;412.77503760450554;423.82834951844495;18616.34842780503;19572.902135489338;20541.486462838435;20560.794392056076;20634.793652063476;12538.81191782124;12584.559540416087;12822.80765788514;12908.548200812973;12954.417051232693;5292.153255479134;5408.08062629352;5444.128939369702;5447.4824891635235;5469.15228139639;7136.107986501687;7526.247375262457;7530.887036694454;7574.8485030299635;7609.581473018981;6832.658367081626;7228.0603521542125;7256.419486441072;7455;7540.434467414966;7125.5724656520515;7205.387542058931;7251.166115896686;7641.961790191069;7672.769816905502;7226.783196504113;7314.378277846389;7355.080614923135;7370.447216458733;7411.147718012444;25716.09993650232;25901.35246618831;26530.73469265302;27229.322353714437;27517.587236191473;2429.2347910408166;2482.4290413204885;2582.9612555811677;2609.4781043791318;2669.5862141367925;16343.26455309514;16424.917875410665;16548.42080527188;16694.49916502507;16856.83143168565;2216.822654187661;2222.377734234417;2236.2285011670947;2244.079927229844;2384.6899903012672;98899;101520.49239753901;103222;105699;106046.46976765143;18356.724649130272;18735;18858.057097145087;19227.327043553687;19337.709934350994;34568.82715586406;34758.30483390368;34791.4781278281;34984.300313993575;35240.82379588137;10683.893161068445;11031.393273370042;11036;11050.33697978124;11147.442627868573;9449.716508504756;9506.952465237768;9562.856557151648;9597.856032159523;9614.615415383447;9443.76390590227;9531.142197202285;9572.56923438447;9662.613495460102;9683.78952630921;3299.6609458562393;3538.2746536960376;3551;3636.3636363636256;3789.204267103884;1037.621268237103;1043.9112675422523;1112.6495154026456;1123;1142.5315620595516;19144.712829307573;19365.80634193668;19578.216871325272;19745.703814442793;20346.694799578017;1031.5358088859894;1061.692109288313;1084.7071290751453;1088.2871719024051;1129.3224065560762;10839.891601084046;11177.608783692533;11210.327380357205;11255.380954047443;11277.32336059839;444578;454371;454512;466208;479666;75761;91492;97185;100791.49604251931;102670.48664756629;1068.428390810928;1078.985753391809;1110.7500812317164;1122.0238392598492;1138.1748232531183;17952.102394880465;18128.90935545314;18138.546536336697;18343.544179817374;18399.17203725809;43068.56931430646;43286.350704740085;44513.55486445094;44876.77561612171;45431.54568454406;61082;61810.690946546776;61871.38128618836;63020.36979630145;63119.36880631135;8606.182412670787;8827.293816494794;8986.415882967585;9098.90901091007;9113.043130471206;13445.260510672008;13463.798043029557;13567.38946747379;13767.862321377059;13894.444222231401;11338;11403.657890263305;11585.768284634429;11604.245724027911;11813.291202527877;10873.891261087605;11532.769344613229;11585;11710.063194944214;11936.16446848747;11865.169438139592;12039.939800300943;12149.635510934686;12257.38713064361;12289.385530723606;7848.64681089341;8949.149830766068;9031.81936361256;9065.546722663972;9073.682421115493;11883.881161188277;12507.249565026128;12533.248005119722;12608.936955315165;12615.432305546456 08:19:49 INFO - PROCESS | 3460 | |3;mootools.html;1569.6232904103015;1574.3781206423464;1808.430344441501;1888.2635772048895;1922.567422329976;8403.453775504588;9098.135677110673;9123.589438475268;9858.211343092562;9862.063104005118;9182.954085229581;9546.28402869784;9604.519774011307;9606.903930960689;9627.855582166274;2441.072392490851;2457.9631305530434;2458.6926634170727;2481.8510889346608;2488.054539275073;2386.904523819045;2479.776820086192;2488;2491.202815099169;2495.1017633651873;2426.162973774045;2435.1476983055877;2472.8887200076038;2473.5794914864427;2475.5667758142345;2339.356676913845;2365.5860224460735;2369.9644505332435;2416.3355077353694;2424.527217192648;2436.110819550869;2450.424150324672;2479.4917042006346;2481.466484705797;2482.751724827521;9546.427214367091;9560.521973901277;9632.662856800049;9636.614535418576;9644.807103857884;5527.143292789584;5645.294338207724;5682.51698605619;5712;5788.160716696056;3479.9652003479896;3492.3713731528314;3546.627604101558;3583.4087375583;3679.9448008279896;1164.1385374822655;1165.0912288414966;1172.6950992741863;1173.90021848143;1195.0320240605138;13789.724205515933;13979.510717124851;14025.088369255966;14027.71944561103;14085.577432676933;4891.241857512091;4916.557509824133;4941.184704523714;4983.651144419929;5044.243363495431;11568.479418426192;11574.189806713617;11648.592299269485;11692.941535292439;11734.119941004288;5647.181158732043;5709.628874123168;5733.799317023884;5737.569682273846;5757.683327417032;4619.3071039343695;5037.672551284228;5038.84883453497;5273.209018647157;5278.81524146653;4318.438602981592;4367.67242456817;4394.978025109854;4473.172463094338;4478.328250762412;1329.674229813702;1334.099482849073;1342.8052932324758;1353.2421843767324;1371.636516323175;977.9608815647437;1090.3621381491917;1101.6970333158445;1104.6078642081945;1141.6460897121754;6558.245801732862;6613.933860661332;6693.464522838161;6739.629320387426;6876.140482439695;465.4577417308865;466.38204379197697;470.3790995885398;475.46747642640463;484.370318585837;4782.760861956958;4901.460839307673;4909.189983652657;4920.6309526785635;5027.271045698353;12260.080493962989;12362.505499780087;12365.134440589072;12499.625011249676;12532.37338133108;11295.265807722637;11735.88264117365;12259.203151795105;12368.876311236772;12383.75232495345;8733.25767309773;9056.320775941831;9092.090790920987;9121.954390228006;9186.219171370509;6418.807435776934;6422.935770642421;6493.610383377012;6584.275729669828;6611.801645950629;10461.581536738446;11066.502007409847;11174.938380853806;11210.66368008961;11228.606998755004;10969;11119.610813621484;11142.888571114185;11147.331160130418;11237.943810280896;9152.679656212;9179.678711245075;9181;9201.401908875901;9282.907170928474;9153.038930912158;9192.080791920755;9220.815583688423;9238.260939124988;9244.214241789523;7691.50005249657;7827.139014708264;7847.686092556462;7939.801504962422;7956.880646790187;8136.552489613128;8267.255946964802;8280.792980175544;8323.6670533178;8383.496990180609;7710.961445192738;7739.380849532136;7834.647440865061;7865.213478652089;7897.05235371759;5759.465835032573;5763.827085187451;5866.794662186803;5892.911606325994;5896.103896103896;5432.728363581884;5472.179173124063;5576.052071147996;5801.361850196559;5848.766049357978 08:19:49 INFO - PROCESS | 3460 | |4;prototype.html;349.2804822066543;350.3501005634549;355.0484701001317;358.51600339541636;361.77569906657874;5733.598648094635;5771.056761051862;5771.769129234831;5824.446677565635;5860.296764388279;5671.035923892938;5682.715864206793;5690.7723691052415;5712.771489140429;5723.5135013523795;4284.507281662618;4355.54266801986;4374.993751437171;4391;4402.889927751816;4321.428749893808;4369.956300437003;4391.3193455014525;4395.604395604395;4410.911781764363;4326.913461730779;4402.625776808975;4417.381566580665;4421.159979603881;4430.579094985971;4194.7692876891915;4373.431453911003;4385.320275357325;4387.561243875619;4441.200583894898;3837.3860182370686;3845.730798844082;3858.3633700439395;3867;3871.632194941476;5535.225068490415;5553.8333849984565;5565.220869078333;5578.525825304854;5603.674475092832;4921.1419444998855;5018.121828679967;5029.321041659366;5051.1413059779925;5070.949290507085;5891.587588868781;5934.8813023739285;5971.790987315423;5972.910406343908;5981.401859813984;4271.508776490712;4276.294411422112;4292.905309146147;4296.2696341621995;4312.460942382202;5973.790917317917;6078.169266988578;6108.328083910811;6175.59858609189;6208.937910620881;4320;4347.304431290978;4347.6304514116655;4359.978200108979;4364.476262848479;4392.802323895432;4409.691321607521;4410.823567057282;4426.579474949939;4460.487043989918;4908.901821963612;4966.255061740768;5027.597792176617;5035.370578677665;5063.493650634907;5857.828434313069;5905.232319798399;5924.170616113747;5940.792072277536;5959.612625179349;1497.610621238464;1501.4444655477544;1501.5345242974495;1524.146477972339;1528.5108765195253;924.9445033298024;959.9424034557949;962.153305091515;969.7963427680266;974.201155052864;5090.567301779316;5184.500462188008;5188.948110518922;5208.713520756395;5258.763355649005;5334.51989320963;5404.756785944642;5410.756515956791;5432.782688692487;5455.536279416215;5031.4213865405945;5190.624484511607;5213.582913366921;5213.791448342024;5244.685318880879;2902.8693708783157;2908.5055540558155;2924.9817625838105;2926.3708302715017;2929.2530404746653;5632.563696257428;5634.52106570946;5646.661200327993;5673.574481913873;5678.4605462480995;5216.686998780086;5463.754131064112;5551.3893471718075;5617.494425501643;5634.74643641029;5507.779688812483;5553.083741182741;5608.439156084441;5644.520215781622;5715.31416230055;5286.312779338661;5474.233607294902;5506.834794956158;5507.531859791962;5509.449055094618;5366.973165134305;5389.299391079135;5414.7021913795115;5419.566434685295;5446.537044351275;6207.255129384503;6410.5639856854095;6414.358564143548;6433.453156481564;6452.54832161763;4438.889027774202;4498.122866041091;4520.706154099973;4527.750973696347;4546.522615125365;6016.669083200466;6123.724432400463;6154.692265386623;6232.968835155976;6266.216722909637;5823.7379317931645;5875.706214689334;5960.761569537343;5984.551158663205;5996.67018313997;5936.3470018297085;5946.554008449297;5950.910736339045;5989.8203053908455;6024.909626355696;5724.8282551523525;5859.472647461749;5877.676727780014;5879.853003674772;5953.464188223081;5999.130126131599;6049.274087109575;6104.786332478342;6109.602875813058;6134.539909506894;5647.588102974257;5863.355030946678;5884.205632239764;5967.389729213936;5983.431574000463 08:19:49 INFO - PROCESS | 3460 | |5;yui.html;769.9345555627772;786.7285786403688;862.7756783236358;873.1486800369645;898.8516894712373;15165.848341516581;16098.51704448867;16105.83894161058;16112.75830862538;16701.081440520797;15581.688366232669;15712.821538384638;15833.524994250161;15834.4457943972;15856.207189640501;699.6991293743688;842.6334544473143;869.9826003479958;873.2795443758893;878.7715194049529;804.1717031457579;816.9346452283833;822.2435359469303;822.8436597046572;823.8352329534088;886.7960369115108;899.1503029636996;901.5041727050129;901.8737376767258;902.4179404284265;899.8740176375313;900.1133883125149;902.4720538484994;903.6520939438298;904.8099899021213;664.5514277862471;664.6610228783355;664.936831001059;665.1718610330122;666.5467482112161;16227.107509086996;16276.186190690418;16336.918315408348;16359.672806543922;16388.26252818626;12669.176503527242;13010;13137.934310328386;13423.731525369536;13720.65698357539;11840.467178976967;11935.641930742091;11954.880451195464;11980.58067967617;12135.635930922086;4588.17412865684;4599.724016559017;4629.976850115761;4688.085823264465;4728.881777955544;84911;85065;85557.99999999937;85818;85886.57056714738;10787.083097936606;10902.127829773757;10968.945155274332;10977.780444391226;11140;18769.81230187708;19258.170473804967;19284.325048623232;21487.140514379564;21690.783092168876;8334.16658334174;8502;8619.094995025433;8625.482471051757;8721.956390218009;4698.483166851712;4753.881152971203;4782.617390608743;4981.701097934063;4999.925001124986;3800.771953682788;3928.273269445162;3982.4424580558166;3991.4012898064943;4008.4588580541254;649.7790751144539;651.6578796132069;658.0622612776774;663.6714826160985;664.2925284571916;696.6760456387804;790.2768966395683;800.931920786728;810.5906517208772;815.2703330519153;9439.622415103318;9735.878830855489;9787.90212097884;9871.40771553709;9955.751106222402;632.9429852146926;697.6581475077277;747.6261869065468;782.8434313137282;789.2305002622512;4630.097131059479;4694.741789201558;4697.741624210701;4727.598154156866;4762.880927976828;119852;120768;124040;124276.37861810633;125308.37345813212;69586.65206673935;100561;105774;108992.4550377243;109819;90253;92442;92985;93125.5343723277;93318;9536.380135291183;9595.42427454355;9684.08001239881;9711.98024207448;9819.45992970365;53422.46577534175;54418.45581544134;55357.72321138369;55440.72279638576;56365.71817140888;79973;80250;80498;81340.59329703313;81368;11797.17419780607;11980.400979950793;12077.154599177973;12164.635060948185;12594.370281485706;12676;13202.669933251746;13421.597352079454;13458.663533411744;14089.506867259597;13169.209847409185;13975.860241397862;14031.719365612835;14077.225752583707;14378.137311761328;13120.540781072617;13232.338383081;13295.800562991757;13812.240326782123;13816.792748108586;13468.932655337052;13637.18176909349;13669.453221871412;13716.862831371558;13840.653983650489;10735.677929662123;10765;10916;11082;11165.609203677832;10407.271490995558;10821.296615719953;10879.836802448128;10904.672859814218;10910.509027093642 08:19:49 INFO - PROCESS | 3460 | __end_tp_report 08:19:49 INFO - PROCESS | 3460 | __start_cc_report 08:19:49 INFO - PROCESS | 3460 | _x_x_mozilla_cycle_collect,326 08:19:49 INFO - PROCESS | 3460 | __end_cc_report 08:19:49 INFO - PROCESS | 3460 | __startTimestamp1468077589321__endTimestamp 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | ------- Summary: start ------- 08:19:49 INFO - PROCESS | 3460 | Number of tests: 6 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | [#0] dojo.html Cycles:165 Average:5729.66 Median:6014.37 stddev:669.24 (11.1%) stddev-sans-first:645.16 08:19:49 INFO - PROCESS | 3460 | Values: 3368.7 3385.7 3385.8 3423.0 3429.4 5663.6 5674.1 5677.8 5727.6 5772.3 5615.6 5688.5 5717.0 5723.3 5735.7 5158.8 5168.1 5171.7 5223.9 5252.7 5045.1 5081.6 5237.5 5240.3 5259.5 5117.3 5131.4 5168.4 5199.6 5253.3 5194.7 5198.7 5228.1 5233.8 5249.8 4995.6 5070.1 5150.9 5193.7 5208.8 5583.8 5593.3 5615.9 5663.3 5672.9 6188.3 6188.6 6216.1 6218.4 6252.4 6037.1 6175.4 6291.7 6303.5 6327.4 5762.5 5854.2 5867.6 5890.1 5937.4 6407.2 6417.6 6432.5 6433.6 6439.8 5879.1 5945.3 5974.8 6093.3 6105.9 6157.6 6162.2 6162.3 6167.3 6168.3 6004.9 6075.1 6093.4 6096.5 6142.7 5915.8 6051.0 6068.6 6070.2 6099.4 4657.5 4672.2 4682.3 4683.8 4698.1 4153.9 4167.8 4176.0 4190.2 4226.4 5776.6 5970.9 6004.0 6036.7 6093.8 6118.7 6132.0 6161.1 6213.5 6254.1 5780.3 6024.1 6025.8 6064.4 6077.3 5197.5 5223.1 5232.8 5272.4 5313.5 6493.6 6509.3 6526.9 6537.3 6544.1 6090.5 6122.4 6279.0 6281.1 6282.5 6229.6 6430.4 6444.3 6444.5 6457.8 5956.3 6026.6 6031.3 6040.7 6056.5 6096.5 6106.6 6136.3 6223.6 6231.6 6048.6 6051.1 6058.1 6083.0 6098.1 6056.5 6078.6 6091.9 6106.5 6110.0 5803.9 5884.8 5897.6 5951.6 6014.5 6077.8 6091.7 6127.6 6129.6 6164.4 5958.4 5979.6 6014.4 6014.4 6015.5 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | [#1] ext.html Cycles:180 Average:69935.64 Median:24332.34 stddev:157182.31 (646.0%) stddev-sans-first:157537.03 08:19:49 INFO - PROCESS | 3460 | Values: 594.9 643.5 645.4 664.3 677.0 40462.8 45595.5 45675.5 45722.0 45822.0 47116.3 47489.5 47731.3 47750.5 47917.3 8690.3 8807.6 8840.5 8884.6 8941.7 8867.2 8883.1 8890.9 8911.7 8967.4 8990.5 9013.6 9019.6 9031.9 9047.2 8917.2 8934.5 8964.4 8971.2 8988.9 8868.6 8880.3 8909.7 8960.0 8997.7 43746.6 44065.1 44103.9 44121.0 44182.6 3872.0 4174.9 4225.5 4356.4 4482.1 34543.8 35139.0 35338.3 36308.5 36324.8 4504.2 4613.3 4631.5 4693.4 4877.0 401476.0 401677.0 403097.0 404864.0 405052.0 37911.8 66570.0 67176.3 67180.7 67647.0 39433.2 41420.6 41529.4 41779.0 41799.4 17193.6 17231.7 17355.0 17373.3 17424.2 13274.9 13321.5 13369.5 13390.9 13406.2 9223.4 9271.3 9297.9 9298.1 9324.7 3428.9 3524.0 3551.3 3561.8 3600.4 1086.5 1093.4 1096.0 1194.4 1213.1 34089.3 34186.0 34243.0 34422.8 34811.0 1198.5 1199.7 1218.3 1234.7 1272.2 17775.2 18087.5 18140.6 18258.3 18284.9 648838.0 860612.0 866156.0 870090.0 879402.0 62449.0 62572.7 62825.4 62835.1 63191.0 1468.8 1498.9 1510.0 1525.4 1528.3 32493.5 33086.8 33305.3 33328.3 33735.5 54461.7 54823.5 55181.2 55224.2 55266.7 409881.0 415701.0 415976.0 418271.0 419818.0 30253.2 30327.0 30570.8 31090.2 31663.7 53703.7 53965.7 54314.0 54620.9 55340.7 45275.8 45484.1 45932.0 46846.3 47399.3 45010.0 46001.8 46141.0 46500.5 47004.0 45176.3 46300.8 46354.3 46675.3 48989.0 38493.0 39067.4 39204.8 40032.6 40085.2 29695.4 30632.2 30858.0 31017.7 31198.4 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | [#2] jquery.html Cycles:180 Average:30489.27 Median:2406.96 stddev:76390.86 (3173.7%) stddev-sans-first:76571.76 08:19:49 INFO - PROCESS | 3460 | Values: 405.8 409.8 412.6 412.8 423.8 18616.3 19572.9 20541.5 20560.8 20634.8 12538.8 12584.6 12822.8 12908.5 12954.4 5292.2 5408.1 5444.1 5447.5 5469.2 7136.1 7526.2 7530.9 7574.8 7609.6 6832.7 7228.1 7256.4 7455.0 7540.4 7125.6 7205.4 7251.2 7642.0 7672.8 7226.8 7314.4 7355.1 7370.4 7411.1 25716.1 25901.4 26530.7 27229.3 27517.6 2429.2 2482.4 2583.0 2609.5 2669.6 16343.3 16424.9 16548.4 16694.5 16856.8 2216.8 2222.4 2236.2 2244.1 2384.7 98899.0 101520.5 103222.0 105699.0 106046.5 18356.7 18735.0 18858.1 19227.3 19337.7 34568.8 34758.3 34791.5 34984.3 35240.8 10683.9 11031.4 11036.0 11050.3 11147.4 9449.7 9507.0 9562.9 9597.9 9614.6 9443.8 9531.1 9572.6 9662.6 9683.8 3299.7 3538.3 3551.0 3636.4 3789.2 1037.6 1043.9 1112.6 1123.0 1142.5 19144.7 19365.8 19578.2 19745.7 20346.7 1031.5 1061.7 1084.7 1088.3 1129.3 10839.9 11177.6 11210.3 11255.4 11277.3 444578.0 454371.0 454512.0 466208.0 479666.0 75761.0 91492.0 97185.0 100791.5 102670.5 1068.4 1079.0 1110.8 1122.0 1138.2 17952.1 18128.9 18138.5 18343.5 18399.2 43068.6 43286.4 44513.6 44876.8 45431.5 61082.0 61810.7 61871.4 63020.4 63119.4 8606.2 8827.3 8986.4 9098.9 9113.0 13445.3 13463.8 13567.4 13767.9 13894.4 11338.0 11403.7 11585.8 11604.2 11813.3 10873.9 11532.8 11585.0 11710.1 11936.2 11865.2 12039.9 12149.6 12257.4 12289.4 7848.6 8949.1 9031.8 9065.5 9073.7 11883.9 12507.2 12533.2 12608.9 12615.4 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | [#3] mootools.html Cycles:180 Average:6360.04 Median:2687.81 stddev:3705.90 (137.9%) stddev-sans-first:3698.82 08:19:49 INFO - PROCESS | 3460 | Values: 1569.6 1574.4 1808.4 1888.3 1922.6 8403.5 9098.1 9123.6 9858.2 9862.1 9183.0 9546.3 9604.5 9606.9 9627.9 2441.1 2458.0 2458.7 2481.9 2488.1 2386.9 2479.8 2488.0 2491.2 2495.1 2426.2 2435.1 2472.9 2473.6 2475.6 2339.4 2365.6 2370.0 2416.3 2424.5 2436.1 2450.4 2479.5 2481.5 2482.8 9546.4 9560.5 9632.7 9636.6 9644.8 5527.1 5645.3 5682.5 5712.0 5788.2 3480.0 3492.4 3546.6 3583.4 3679.9 1164.1 1165.1 1172.7 1173.9 1195.0 13789.7 13979.5 14025.1 14027.7 14085.6 4891.2 4916.6 4941.2 4983.7 5044.2 11568.5 11574.2 11648.6 11692.9 11734.1 5647.2 5709.6 5733.8 5737.6 5757.7 4619.3 5037.7 5038.8 5273.2 5278.8 4318.4 4367.7 4395.0 4473.2 4478.3 1329.7 1334.1 1342.8 1353.2 1371.6 978.0 1090.4 1101.7 1104.6 1141.6 6558.2 6613.9 6693.5 6739.6 6876.1 465.5 466.4 470.4 475.5 484.4 4782.8 4901.5 4909.2 4920.6 5027.3 12260.1 12362.5 12365.1 12499.6 12532.4 11295.3 11735.9 12259.2 12368.9 12383.8 8733.3 9056.3 9092.1 9122.0 9186.2 6418.8 6422.9 6493.6 6584.3 6611.8 10461.6 11066.5 11174.9 11210.7 11228.6 10969.0 11119.6 11142.9 11147.3 11237.9 9152.7 9179.7 9181.0 9201.4 9282.9 9153.0 9192.1 9220.8 9238.3 9244.2 7691.5 7827.1 7847.7 7939.8 7956.9 8136.6 8267.3 8280.8 8323.7 8383.5 7711.0 7739.4 7834.6 7865.2 7897.1 5759.5 5763.8 5866.8 5892.9 5896.1 5432.7 5472.2 5576.1 5801.4 5848.8 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | [#4] prototype.html Cycles:180 Average:4870.85 Median:5451.04 stddev:1419.20 (26.0%) stddev-sans-first:1382.01 08:19:49 INFO - PROCESS | 3460 | Values: 349.3 350.4 355.0 358.5 361.8 5733.6 5771.1 5771.8 5824.4 5860.3 5671.0 5682.7 5690.8 5712.8 5723.5 4284.5 4355.5 4375.0 4391.0 4402.9 4321.4 4370.0 4391.3 4395.6 4410.9 4326.9 4402.6 4417.4 4421.2 4430.6 4194.8 4373.4 4385.3 4387.6 4441.2 3837.4 3845.7 3858.4 3867.0 3871.6 5535.2 5553.8 5565.2 5578.5 5603.7 4921.1 5018.1 5029.3 5051.1 5070.9 5891.6 5934.9 5971.8 5972.9 5981.4 4271.5 4276.3 4292.9 4296.3 4312.5 5973.8 6078.2 6108.3 6175.6 6208.9 4320.0 4347.3 4347.6 4360.0 4364.5 4392.8 4409.7 4410.8 4426.6 4460.5 4908.9 4966.3 5027.6 5035.4 5063.5 5857.8 5905.2 5924.2 5940.8 5959.6 1497.6 1501.4 1501.5 1524.1 1528.5 924.9 959.9 962.2 969.8 974.2 5090.6 5184.5 5188.9 5208.7 5258.8 5334.5 5404.8 5410.8 5432.8 5455.5 5031.4 5190.6 5213.6 5213.8 5244.7 2902.9 2908.5 2925.0 2926.4 2929.3 5632.6 5634.5 5646.7 5673.6 5678.5 5216.7 5463.8 5551.4 5617.5 5634.7 5507.8 5553.1 5608.4 5644.5 5715.3 5286.3 5474.2 5506.8 5507.5 5509.4 5367.0 5389.3 5414.7 5419.6 5446.5 6207.3 6410.6 6414.4 6433.5 6452.5 4438.9 4498.1 4520.7 4527.8 4546.5 6016.7 6123.7 6154.7 6233.0 6266.2 5823.7 5875.7 5960.8 5984.6 5996.7 5936.3 5946.6 5950.9 5989.8 6024.9 5724.8 5859.5 5877.7 5879.9 5953.5 5999.1 6049.3 6104.8 6109.6 6134.5 5647.6 5863.4 5884.2 5967.4 5983.4 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - PROCESS | 3460 | [#5] yui.html Cycles:180 Average:21699.87 Median:4698.11 stddev:31988.29 (680.9%) stddev-sans-first:32039.42 08:19:49 INFO - PROCESS | 3460 | Values: 769.9 786.7 862.8 873.1 898.9 15165.8 16098.5 16105.8 16112.8 16701.1 15581.7 15712.8 15833.5 15834.4 15856.2 699.7 842.6 870.0 873.3 878.8 804.2 816.9 822.2 822.8 823.8 886.8 899.2 901.5 901.9 902.4 899.9 900.1 902.5 903.7 904.8 664.6 664.7 664.9 665.2 666.5 16227.1 16276.2 16336.9 16359.7 16388.3 12669.2 13010.0 13137.9 13423.7 13720.7 11840.5 11935.6 11954.9 11980.6 12135.6 4588.2 4599.7 4630.0 4688.1 4728.9 84911.0 85065.0 85558.0 85818.0 85886.6 10787.1 10902.1 10968.9 10977.8 11140.0 18769.8 19258.2 19284.3 21487.1 21690.8 8334.2 8502.0 8619.1 8625.5 8722.0 4698.5 4753.9 4782.6 4981.7 4999.9 3800.8 3928.3 3982.4 3991.4 4008.5 649.8 651.7 658.1 663.7 664.3 696.7 790.3 800.9 810.6 815.3 9439.6 9735.9 9787.9 9871.4 9955.8 632.9 697.7 747.6 782.8 789.2 4630.1 4694.7 4697.7 4727.6 4762.9 119852.0 120768.0 124040.0 124276.4 125308.4 69586.7 100561.0 105774.0 108992.5 109819.0 90253.0 92442.0 92985.0 93125.5 93318.0 9536.4 9595.4 9684.1 9712.0 9819.5 53422.5 54418.5 55357.7 55440.7 56365.7 79973.0 80250.0 80498.0 81340.6 81368.0 11797.2 11980.4 12077.2 12164.6 12594.4 12676.0 13202.7 13421.6 13458.7 14089.5 13169.2 13975.9 14031.7 14077.2 14378.1 13120.5 13232.3 13295.8 13812.2 13816.8 13468.9 13637.2 13669.5 13716.9 13840.7 10735.7 10765.0 10916.0 11082.0 11165.6 10407.3 10821.3 10879.8 10904.7 10910.5 08:19:49 INFO - PROCESS | 3460 | -------- Summary: end -------- 08:19:49 INFO - PROCESS | 3460 | 08:19:49 INFO - TEST-INFO | 3460: exit 0 08:19:50 INFO - TEST-OK | dromaeo_css | took 1111670ms 08:19:50 INFO - TEST-START | kraken 08:19:50 INFO - operating with platform_type : linux_ 08:19:50 INFO - Initialising browser for kraken test... 08:19:50 INFO - TEST-INFO | started process 3703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbrkOfb/profile http://localhost:43945/getInfo.html) 08:19:55 INFO - PROCESS | 3703 | __metrics Screen width/height:1600/1200 08:19:55 INFO - PROCESS | 3703 | colorDepth:24 08:19:55 INFO - PROCESS | 3703 | Browser inner width/height: 1024/697 08:19:55 INFO - PROCESS | 3703 | __metrics 08:19:55 ERROR - PROCESS | 3703 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 08:19:56 INFO - TEST-INFO | 3703: exit 0 08:19:56 INFO - Browser initialized. 08:19:56 INFO - Running cycle 1/1 for kraken test... 08:19:56 INFO - TEST-INFO | started process 3830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbrkOfb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:20:08 INFO - PROCESS | 3830 | RSS: Main: 156049408 08:20:08 INFO - PROCESS | 3830 | 08:20:11 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43945/tests/kraken/driver.html?testName=audio-beat-detection) 08:20:11 INFO - PROCESS | 3830 | RSS: Main: 161001472 08:20:11 INFO - PROCESS | 3830 | 08:20:14 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43945/tests/kraken/driver.html?testName=audio-dft) 08:20:15 INFO - PROCESS | 3830 | RSS: Main: 160092160 08:20:15 INFO - PROCESS | 3830 | 08:20:18 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43945/tests/kraken/driver.html?testName=audio-fft) 08:20:18 INFO - PROCESS | 3830 | RSS: Main: 160923648 08:20:18 INFO - PROCESS | 3830 | 08:20:20 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43945/tests/kraken/driver.html?testName=audio-oscillator) 08:20:21 INFO - PROCESS | 3830 | RSS: Main: 160698368 08:20:21 INFO - PROCESS | 3830 | 08:20:23 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:20:24 INFO - PROCESS | 3830 | RSS: Main: 160481280 08:20:24 INFO - PROCESS | 3830 | 08:20:27 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43945/tests/kraken/driver.html?testName=imaging-darkroom) 08:20:27 INFO - PROCESS | 3830 | RSS: Main: 160219136 08:20:27 INFO - PROCESS | 3830 | 08:20:31 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43945/tests/kraken/driver.html?testName=imaging-desaturate) 08:20:32 INFO - PROCESS | 3830 | RSS: Main: 156852224 08:20:32 INFO - PROCESS | 3830 | 08:20:34 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43945/tests/kraken/driver.html?testName=json-parse-financial) 08:20:35 INFO - PROCESS | 3830 | RSS: Main: 158326784 08:20:35 INFO - PROCESS | 3830 | 08:20:36 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:20:37 INFO - PROCESS | 3830 | RSS: Main: 158679040 08:20:37 INFO - PROCESS | 3830 | 08:20:39 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:20:39 INFO - PROCESS | 3830 | RSS: Main: 158777344 08:20:39 INFO - PROCESS | 3830 | 08:20:42 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:20:42 INFO - PROCESS | 3830 | RSS: Main: 159043584 08:20:42 INFO - PROCESS | 3830 | 08:20:45 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:20:45 INFO - PROCESS | 3830 | RSS: Main: 159727616 08:20:45 INFO - PROCESS | 3830 | 08:20:48 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:20:49 INFO - PROCESS | 3830 | RSS: Main: 159752192 08:20:49 INFO - PROCESS | 3830 | 08:20:50 INFO - PROCESS | 3830 | Cycle 1(1): loaded http://localhost:43945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43945/tests/kraken/driver.html?testName=ai-astar) 08:20:51 INFO - PROCESS | 3830 | RSS: Main: 160378880 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | __start_tp_report 08:20:51 INFO - PROCESS | 3830 | _x_x_mozilla_page_load 08:20:51 INFO - PROCESS | 3830 | _x_x_mozilla_page_load_details 08:20:51 INFO - PROCESS | 3830 | |i|pagename|runs| 08:20:51 INFO - PROCESS | 3830 | |0;ai-astar;112;106;110;111;115;114;111;112;112;112 08:20:51 INFO - PROCESS | 3830 | |1;audio-beat-detection;156;125;165;166;128;153;153;126;178;131 08:20:51 INFO - PROCESS | 3830 | |2;audio-dft;164;162;168;162;163;164;163;163;160;163 08:20:51 INFO - PROCESS | 3830 | |3;audio-fft;74;75;75;165;75;77;75;75;167;75 08:20:51 INFO - PROCESS | 3830 | |4;audio-oscillator;86;84;150;84;86;85;140;85;87;140 08:20:51 INFO - PROCESS | 3830 | |5;imaging-gaussian-blur;118;117;117;118;118;118;118;117;117;117 08:20:51 INFO - PROCESS | 3830 | |6;imaging-darkroom;147;148;148;147;147;148;147;147;149;148 08:20:51 INFO - PROCESS | 3830 | |7;imaging-desaturate;96;97;96;95;96;96;96;95;96;96 08:20:51 INFO - PROCESS | 3830 | |8;json-parse-financial;54;54;53;56;53;53;53;52;54;55 08:20:51 INFO - PROCESS | 3830 | |9;json-stringify-tinderbox;59;60;60;60;59;59;59;60;59;60 08:20:51 INFO - PROCESS | 3830 | |10;stanford-crypto-aes;76;76;76;77;77;77;78;80;107;77 08:20:51 INFO - PROCESS | 3830 | |11;stanford-crypto-ccm;121;116;120;117;120;130;119;120;139;121 08:20:51 INFO - PROCESS | 3830 | |12;stanford-crypto-pbkdf2;165;157;157;154;160;164;154;156;155;157 08:20:51 INFO - PROCESS | 3830 | |13;stanford-crypto-sha256-iterative;62;62;62;62;63;64;61;61;71;62 08:20:51 INFO - PROCESS | 3830 | __end_tp_report 08:20:51 INFO - PROCESS | 3830 | __start_cc_report 08:20:51 INFO - PROCESS | 3830 | _x_x_mozilla_cycle_collect,874 08:20:51 INFO - PROCESS | 3830 | __end_cc_report 08:20:51 INFO - PROCESS | 3830 | __startTimestamp1468077651049__endTimestamp 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | ------- Summary: start ------- 08:20:51 INFO - PROCESS | 3830 | Number of tests: 14 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:2.42 (2.2%) stddev-sans-first:2.55 08:20:51 INFO - PROCESS | 3830 | Values: 112.0 106.0 110.0 111.0 115.0 114.0 111.0 112.0 112.0 112.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:154.50 stddev:19.23 (12.4%) stddev-sans-first:20.19 08:20:51 INFO - PROCESS | 3830 | Values: 156.0 125.0 165.0 166.0 128.0 153.0 153.0 126.0 178.0 131.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.04 (1.3%) stddev-sans-first:2.15 08:20:51 INFO - PROCESS | 3830 | Values: 164.0 162.0 168.0 162.0 163.0 164.0 163.0 163.0 160.0 163.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.00 stddev:38.33 (51.1%) stddev-sans-first:40.01 08:20:51 INFO - PROCESS | 3830 | Values: 74.0 75.0 75.0 165.0 75.0 77.0 75.0 75.0 167.0 75.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:85.00 stddev:28.19 (33.2%) stddev-sans-first:29.24 08:20:51 INFO - PROCESS | 3830 | Values: 86.0 84.0 150.0 84.0 86.0 85.0 140.0 85.0 87.0 140.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 08:20:51 INFO - PROCESS | 3830 | Values: 118.0 117.0 117.0 118.0 118.0 118.0 118.0 117.0 117.0 117.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 08:20:51 INFO - PROCESS | 3830 | Values: 147.0 148.0 148.0 147.0 147.0 148.0 147.0 147.0 149.0 148.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:20:51 INFO - PROCESS | 3830 | Values: 96.0 97.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 96.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.16 (2.1%) stddev-sans-first:1.22 08:20:51 INFO - PROCESS | 3830 | Values: 54.0 54.0 53.0 56.0 53.0 53.0 53.0 52.0 54.0 55.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 08:20:51 INFO - PROCESS | 3830 | Values: 59.0 60.0 60.0 60.0 59.0 59.0 59.0 60.0 59.0 60.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:77.00 stddev:9.53 (12.4%) stddev-sans-first:9.99 08:20:51 INFO - PROCESS | 3830 | Values: 76.0 76.0 76.0 77.0 77.0 77.0 78.0 80.0 107.0 77.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:120.50 stddev:6.96 (5.8%) stddev-sans-first:7.37 08:20:51 INFO - PROCESS | 3830 | Values: 121.0 116.0 120.0 117.0 120.0 130.0 119.0 120.0 139.0 121.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:157.00 stddev:3.90 (2.5%) stddev-sans-first:3.18 08:20:51 INFO - PROCESS | 3830 | Values: 165.0 157.0 157.0 154.0 160.0 164.0 154.0 156.0 155.0 157.0 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - PROCESS | 3830 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:62.00 stddev:2.94 (4.7%) stddev-sans-first:3.10 08:20:51 INFO - PROCESS | 3830 | Values: 62.0 62.0 62.0 62.0 63.0 64.0 61.0 61.0 71.0 62.0 08:20:51 INFO - PROCESS | 3830 | -------- Summary: end -------- 08:20:51 INFO - PROCESS | 3830 | 08:20:51 INFO - TEST-INFO | 3830: exit 0 08:20:52 INFO - TEST-OK | kraken | took 62000ms 08:20:52 INFO - SUITE-END | took 1173s 08:20:52 INFO - Completed test suite (00:19:33) 08:20:52 INFO - javascript benchmark 08:20:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7736.7002794086375, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.7305015598754, 3385.6614338566137, 3385.830708464576, 3422.9731080675806, 3429.3827111120004, 5663.603547751659, 5674.120511320742, 5677.807660391312, 5727.627704199224, 5772.278465191851, 5615.606907516475, 5688.459596338341, 5716.971415142919, 5723.284589426322, 5735.684537350444, 5158.793648254065, 5168.069747445458, 5171.741412929338, 5223.921641175385, 5252.737363131847, 5045.091883460976, 5081.618878584102, 5237.45006774289, 5240.266362709223, 5259.526642602165, 5117.283580298761, 5131.435542090386, 5168.405633352175, 5199.6360254782185, 5253.317068781053, 5194.71429071401, 5198.71407072609, 5228.111221092423, 5233.842984710464, 5249.766304918379, 4995.625328100407, 5070.138076526962, 5150.948490515084, 5193.740312984336, 5208.817691380821, 5583.776648934037, 5593.328800543961, 5615.887682246373, 5663.348714897798, 5672.858178545528, 6188.319284878659, 6188.628682279078, 6216.129741836146, 6218.409251121181, 6252.406021427957, 6037.094435834616, 6175.382461753834, 6291.653959032252, 6303.495720342362, 6327.398897104814, 5762.510186634141, 5854.18041474194, 5867.589268751231, 5890.087036509305, 5937.406259374071, 6407.167068281094, 6417.647029413333, 6432.453241474527, 6433.613983161025, 6439.774607888795, 5879.147523609053, 5945.256842894638, 5974.790882319185, 6093.268807743099, 6105.877882442415, 6157.630542167484, 6162.168107305546, 6162.291336496271, 6167.259928808572, 6168.25980882297, 6004.909238474198, 6075.058365953284, 6093.421124993119, 6096.512279017668, 6142.662153581596, 5915.822525324247, 6051.0, 6068.575199736064, 6070.2412198475195, 6099.359567245464, 4657.510961349078, 4672.2057250267535, 4682.321063445781, 4683.765811709401, 4698.107359601665, 4153.8961525962095, 4167.83328666856, 4175.958240417618, 4190.245755763992, 4226.36604509326, 5776.624519406225, 5970.880582388328, 6004.039353703386, 6036.728347224385, 6093.756249750049, 6118.724657390429, 6131.96934015327, 6161.075838624152, 6213.5339849510565, 6254.093156492283, 5780.277465316835, 6024.096385542204, 6025.794841031811, 6064.36324185963, 6077.301110372276, 5197.506236907488, 5223.085959957069, 5232.816851410183, 5272.367315922037, 5313.521783039545, 6493.577917435352, 6509.2839787623325, 6526.902096468557, 6537.34626537352, 6544.149260596092, 6090.543209259412, 6122.448979591858, 6279.0, 6281.057841323746, 6282.497400207881, 6229.563930525001, 6430.421262086435, 6444.323346048597, 6444.548881578245, 6457.838554036187, 5956.315023772322, 6026.638401695912, 6031.306399764083, 6040.697965101639, 6056.454919057306, 6096.512279017579, 6106.572539922163, 6136.304638670978, 6223.564350495421, 6231.563790534619, 6048.637081775108, 6051.062085376773, 6058.0610005449225, 6082.9695851520455, 6098.054801505774, 6056.545759068175, 6078.635281883101, 6091.939080609137, 6106.480949119375, 6110.0, 5803.854903627274, 5884.823455296348, 5897.64614123154, 5951.642901425928, 6014.518838492822, 6077.817665470043, 6091.725872335667, 6127.571070025056, 6129.632222066691, 6164.4143806338325, 5958.404159584007, 5979.611325263844, 6014.36849130835, 6014.36849130835, 6015.548833837567], "value": 5684.039299885043, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.8631814682623, 643.459494025019, 645.3546453546451, 664.292528457193, 676.9559978601394, 40462.79768601157, 45595.544044559545, 45675.54324456755, 45722.0, 45822.0, 47116.29325560111, 47489.525104748944, 47731.28403073948, 47750.52249477504, 47917.28124078141, 8690.304775617951, 8807.559622018905, 8840.469571825712, 8884.644614215424, 8941.748155258269, 8867.157620026088, 8883.11168883113, 8890.86663700045, 8911.68809091682, 8967.372283940127, 8990.460572365646, 9013.647952807065, 9019.639214431414, 9031.864522032141, 9047.185753282203, 8917.242034427081, 8934.463932164059, 8964.417312874675, 8971.192592666692, 8988.865167022499, 8868.600912958933, 8880.289576833902, 8909.732708018704, 8960.0, 8997.685081022199, 43746.56253437488, 44065.11869762619, 44103.89740256487, 44121.0, 44182.55817441817, 3871.9806400968096, 4174.937375939363, 4225.514065882401, 4356.390105385249, 4482.125985432842, 34543.827280863436, 35139.0, 35338.29323413517, 36308.455373169425, 36324.81837590822, 4504.234280172379, 4613.331066995301, 4631.513691062457, 4693.413323334583, 4876.975615121902, 401476.0, 401677.0, 403097.0, 404863.99999999703, 405052.0, 37911.81044094762, 66570.0, 67176.32823671798, 67180.6640966792, 67646.98529522061, 39433.21133577312, 41420.58579414227, 41529.37705934414, 41779.0, 41799.37300940488, 17193.570160745832, 17231.65536689259, 17355.0, 17373.3050677974, 17424.215910284067, 13274.933625332005, 13321.533746318832, 13369.46522139123, 13390.93304533471, 13406.195628262336, 9223.400478968979, 9271.304652151115, 9297.860532092023, 9298.116678915492, 9324.673636422829, 3428.9314213715584, 3524.0, 3551.271989242251, 3561.786292822387, 3600.369935261319, 1086.5110700184948, 1093.3986307530827, 1096.0354887698934, 1194.3848917807336, 1213.071999920062, 34089.31821363559, 34186.0, 34243.0, 34422.82788586041, 34811.0, 1198.4606926882764, 1199.736058067224, 1218.3055658274875, 1234.6913271682079, 1272.166730791333, 17775.200115994812, 18087.457376278733, 18140.63718725618, 18258.269669213347, 18284.908575457303, 648838.0, 860612.0, 866156.0, 870090.0, 879402.0, 62449.0, 62572.687136564935, 62825.371746282864, 62835.057474137924, 63191.0, 1468.7870258812413, 1498.9475368362055, 1509.9773503397457, 1525.3517255166732, 1528.335174199227, 32493.512597310586, 33086.83456582702, 33305.33389332248, 33328.33343333168, 33735.49396759002, 54461.72769136129, 54823.451765481834, 55181.17228241499, 55224.17163742467, 55266.72366638141, 409881.0, 415701.0, 415976.0, 418271.0, 419818.0, 30253.243668908453, 30327.0, 30570.847145764128, 31090.22274443157, 31663.683363166074, 53703.73148134234, 53965.73017134889, 54314.0, 54620.90758184894, 55340.72329638326, 45275.773621133005, 45484.09031819411, 45932.0, 46846.29730553976, 47399.289010665554, 45010.0, 46001.76999114983, 46141.0, 46500.534994650974, 47004.0, 45176.32235516404, 46300.7684961573, 46354.30468543042, 46675.29987050265, 48989.0, 38493.0, 39067.41398879076, 39204.803975979936, 40032.599674002886, 40085.1982960345, 29695.40609187761, 30632.234194145323, 30858.0, 31017.68982310148, 31198.37603247877], "value": 20578.013677236406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.7829061452124, 409.77871949147465, 412.56062293657243, 412.77503760450554, 423.82834951844495, 18616.34842780503, 19572.902135489338, 20541.486462838435, 20560.794392056076, 20634.793652063476, 12538.81191782124, 12584.559540416087, 12822.80765788514, 12908.548200812973, 12954.417051232693, 5292.153255479134, 5408.08062629352, 5444.128939369702, 5447.4824891635235, 5469.15228139639, 7136.107986501687, 7526.247375262457, 7530.887036694454, 7574.8485030299635, 7609.581473018981, 6832.658367081626, 7228.0603521542125, 7256.419486441072, 7455.0, 7540.434467414966, 7125.5724656520515, 7205.387542058931, 7251.166115896686, 7641.961790191069, 7672.769816905502, 7226.783196504113, 7314.378277846389, 7355.080614923135, 7370.447216458733, 7411.147718012444, 25716.09993650232, 25901.35246618831, 26530.73469265302, 27229.322353714437, 27517.587236191473, 2429.2347910408166, 2482.4290413204885, 2582.9612555811677, 2609.4781043791318, 2669.5862141367925, 16343.26455309514, 16424.917875410665, 16548.42080527188, 16694.49916502507, 16856.83143168565, 2216.822654187661, 2222.377734234417, 2236.2285011670947, 2244.079927229844, 2384.6899903012672, 98899.0, 101520.49239753901, 103222.0, 105699.0, 106046.46976765143, 18356.724649130272, 18735.0, 18858.057097145087, 19227.327043553687, 19337.709934350994, 34568.82715586406, 34758.30483390368, 34791.4781278281, 34984.300313993575, 35240.82379588137, 10683.893161068445, 11031.393273370042, 11036.0, 11050.33697978124, 11147.442627868573, 9449.716508504756, 9506.952465237768, 9562.856557151648, 9597.856032159523, 9614.615415383447, 9443.76390590227, 9531.142197202285, 9572.56923438447, 9662.613495460102, 9683.78952630921, 3299.6609458562393, 3538.2746536960376, 3551.0, 3636.3636363636256, 3789.204267103884, 1037.621268237103, 1043.9112675422523, 1112.6495154026456, 1123.0, 1142.5315620595516, 19144.712829307573, 19365.80634193668, 19578.216871325272, 19745.703814442793, 20346.694799578017, 1031.5358088859894, 1061.692109288313, 1084.7071290751453, 1088.2871719024051, 1129.3224065560762, 10839.891601084046, 11177.608783692533, 11210.327380357205, 11255.380954047443, 11277.32336059839, 444578.0, 454371.0, 454512.0, 466208.0, 479666.0, 75761.0, 91492.0, 97185.0, 100791.49604251931, 102670.48664756629, 1068.428390810928, 1078.985753391809, 1110.7500812317164, 1122.0238392598492, 1138.1748232531183, 17952.102394880465, 18128.90935545314, 18138.546536336697, 18343.544179817374, 18399.17203725809, 43068.56931430646, 43286.350704740085, 44513.55486445094, 44876.77561612171, 45431.54568454406, 61082.0, 61810.690946546776, 61871.38128618836, 63020.36979630145, 63119.36880631135, 8606.182412670787, 8827.293816494794, 8986.415882967585, 9098.90901091007, 9113.043130471206, 13445.260510672008, 13463.798043029557, 13567.38946747379, 13767.862321377059, 13894.444222231401, 11338.0, 11403.657890263305, 11585.768284634429, 11604.245724027911, 11813.291202527877, 10873.891261087605, 11532.769344613229, 11585.0, 11710.063194944214, 11936.16446848747, 11865.169438139592, 12039.939800300943, 12149.635510934686, 12257.38713064361, 12289.385530723606, 7848.64681089341, 8949.149830766068, 9031.81936361256, 9065.546722663972, 9073.682421115493, 11883.881161188277, 12507.249565026128, 12533.248005119722, 12608.936955315165, 12615.432305546456], "value": 10754.427249448163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1569.6232904103015, 1574.3781206423464, 1808.430344441501, 1888.2635772048895, 1922.567422329976, 8403.453775504588, 9098.135677110673, 9123.589438475268, 9858.211343092562, 9862.063104005118, 9182.954085229581, 9546.28402869784, 9604.519774011307, 9606.903930960689, 9627.855582166274, 2441.072392490851, 2457.9631305530434, 2458.6926634170727, 2481.8510889346608, 2488.054539275073, 2386.904523819045, 2479.776820086192, 2488.0, 2491.202815099169, 2495.1017633651873, 2426.162973774045, 2435.1476983055877, 2472.8887200076038, 2473.5794914864427, 2475.5667758142345, 2339.356676913845, 2365.5860224460735, 2369.9644505332435, 2416.3355077353694, 2424.527217192648, 2436.110819550869, 2450.424150324672, 2479.4917042006346, 2481.466484705797, 2482.751724827521, 9546.427214367091, 9560.521973901277, 9632.662856800049, 9636.614535418576, 9644.807103857884, 5527.143292789584, 5645.294338207724, 5682.51698605619, 5712.0, 5788.160716696056, 3479.9652003479896, 3492.3713731528314, 3546.627604101558, 3583.4087375583, 3679.9448008279896, 1164.1385374822655, 1165.0912288414966, 1172.6950992741863, 1173.90021848143, 1195.0320240605138, 13789.724205515933, 13979.510717124851, 14025.088369255966, 14027.71944561103, 14085.577432676933, 4891.241857512091, 4916.557509824133, 4941.184704523714, 4983.651144419929, 5044.243363495431, 11568.479418426192, 11574.189806713617, 11648.592299269485, 11692.941535292439, 11734.119941004288, 5647.181158732043, 5709.628874123168, 5733.799317023884, 5737.569682273846, 5757.683327417032, 4619.3071039343695, 5037.672551284228, 5038.84883453497, 5273.209018647157, 5278.81524146653, 4318.438602981592, 4367.67242456817, 4394.978025109854, 4473.172463094338, 4478.328250762412, 1329.674229813702, 1334.099482849073, 1342.8052932324758, 1353.2421843767324, 1371.636516323175, 977.9608815647437, 1090.3621381491917, 1101.6970333158445, 1104.6078642081945, 1141.6460897121754, 6558.245801732862, 6613.933860661332, 6693.464522838161, 6739.629320387426, 6876.140482439695, 465.4577417308865, 466.38204379197697, 470.3790995885398, 475.46747642640463, 484.370318585837, 4782.760861956958, 4901.460839307673, 4909.189983652657, 4920.6309526785635, 5027.271045698353, 12260.080493962989, 12362.505499780087, 12365.134440589072, 12499.625011249676, 12532.37338133108, 11295.265807722637, 11735.88264117365, 12259.203151795105, 12368.876311236772, 12383.75232495345, 8733.25767309773, 9056.320775941831, 9092.090790920987, 9121.954390228006, 9186.219171370509, 6418.807435776934, 6422.935770642421, 6493.610383377012, 6584.275729669828, 6611.801645950629, 10461.581536738446, 11066.502007409847, 11174.938380853806, 11210.66368008961, 11228.606998755004, 10969.0, 11119.610813621484, 11142.888571114185, 11147.331160130418, 11237.943810280896, 9152.679656212, 9179.678711245075, 9181.0, 9201.401908875901, 9282.907170928474, 9153.038930912158, 9192.080791920755, 9220.815583688423, 9238.260939124988, 9244.214241789523, 7691.50005249657, 7827.139014708264, 7847.686092556462, 7939.801504962422, 7956.880646790187, 8136.552489613128, 8267.255946964802, 8280.792980175544, 8323.6670533178, 8383.496990180609, 7710.961445192738, 7739.380849532136, 7834.647440865061, 7865.213478652089, 7897.05235371759, 5759.465835032573, 5763.827085187451, 5866.794662186803, 5892.911606325994, 5896.103896103896, 5432.728363581884, 5472.179173124063, 5576.052071147996, 5801.361850196559, 5848.766049357978], "value": 4960.411377108802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.2804822066543, 350.3501005634549, 355.0484701001317, 358.51600339541636, 361.77569906657874, 5733.598648094635, 5771.056761051862, 5771.769129234831, 5824.446677565635, 5860.296764388279, 5671.035923892938, 5682.715864206793, 5690.7723691052415, 5712.771489140429, 5723.5135013523795, 4284.507281662618, 4355.54266801986, 4374.993751437171, 4391.0, 4402.889927751816, 4321.428749893808, 4369.956300437003, 4391.3193455014525, 4395.604395604395, 4410.911781764363, 4326.913461730779, 4402.625776808975, 4417.381566580665, 4421.159979603881, 4430.579094985971, 4194.7692876891915, 4373.431453911003, 4385.320275357325, 4387.561243875619, 4441.200583894898, 3837.3860182370686, 3845.730798844082, 3858.3633700439395, 3867.0, 3871.632194941476, 5535.225068490415, 5553.8333849984565, 5565.220869078333, 5578.525825304854, 5603.674475092832, 4921.1419444998855, 5018.121828679967, 5029.321041659366, 5051.1413059779925, 5070.949290507085, 5891.587588868781, 5934.8813023739285, 5971.790987315423, 5972.910406343908, 5981.401859813984, 4271.508776490712, 4276.294411422112, 4292.905309146147, 4296.2696341621995, 4312.460942382202, 5973.790917317917, 6078.169266988578, 6108.328083910811, 6175.59858609189, 6208.937910620881, 4320.0, 4347.304431290978, 4347.6304514116655, 4359.978200108979, 4364.476262848479, 4392.802323895432, 4409.691321607521, 4410.823567057282, 4426.579474949939, 4460.487043989918, 4908.901821963612, 4966.255061740768, 5027.597792176617, 5035.370578677665, 5063.493650634907, 5857.828434313069, 5905.232319798399, 5924.170616113747, 5940.792072277536, 5959.612625179349, 1497.610621238464, 1501.4444655477544, 1501.5345242974495, 1524.146477972339, 1528.5108765195253, 924.9445033298024, 959.9424034557949, 962.153305091515, 969.7963427680266, 974.201155052864, 5090.567301779316, 5184.500462188008, 5188.948110518922, 5208.713520756395, 5258.763355649005, 5334.51989320963, 5404.756785944642, 5410.756515956791, 5432.782688692487, 5455.536279416215, 5031.4213865405945, 5190.624484511607, 5213.582913366921, 5213.791448342024, 5244.685318880879, 2902.8693708783157, 2908.5055540558155, 2924.9817625838105, 2926.3708302715017, 2929.2530404746653, 5632.563696257428, 5634.52106570946, 5646.661200327993, 5673.574481913873, 5678.4605462480995, 5216.686998780086, 5463.754131064112, 5551.3893471718075, 5617.494425501643, 5634.74643641029, 5507.779688812483, 5553.083741182741, 5608.439156084441, 5644.520215781622, 5715.31416230055, 5286.312779338661, 5474.233607294902, 5506.834794956158, 5507.531859791962, 5509.449055094618, 5366.973165134305, 5389.299391079135, 5414.7021913795115, 5419.566434685295, 5446.537044351275, 6207.255129384503, 6410.5639856854095, 6414.358564143548, 6433.453156481564, 6452.54832161763, 4438.889027774202, 4498.122866041091, 4520.706154099973, 4527.750973696347, 4546.522615125365, 6016.669083200466, 6123.724432400463, 6154.692265386623, 6232.968835155976, 6266.216722909637, 5823.7379317931645, 5875.706214689334, 5960.761569537343, 5984.551158663205, 5996.67018313997, 5936.3470018297085, 5946.554008449297, 5950.910736339045, 5989.8203053908455, 6024.909626355696, 5724.8282551523525, 5859.472647461749, 5877.676727780014, 5879.853003674772, 5953.464188223081, 5999.130126131599, 6049.274087109575, 6104.786332478342, 6109.602875813058, 6134.539909506894, 5647.588102974257, 5863.355030946678, 5884.205632239764, 5967.389729213936, 5983.431574000463], "value": 4418.504332920512, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.9345555627772, 786.7285786403688, 862.7756783236358, 873.1486800369645, 898.8516894712373, 15165.848341516581, 16098.51704448867, 16105.83894161058, 16112.75830862538, 16701.081440520797, 15581.688366232669, 15712.821538384638, 15833.524994250161, 15834.4457943972, 15856.207189640501, 699.6991293743688, 842.6334544473143, 869.9826003479958, 873.2795443758893, 878.7715194049529, 804.1717031457579, 816.9346452283833, 822.2435359469303, 822.8436597046572, 823.8352329534088, 886.7960369115108, 899.1503029636996, 901.5041727050129, 901.8737376767258, 902.4179404284265, 899.8740176375313, 900.1133883125149, 902.4720538484994, 903.6520939438298, 904.8099899021213, 664.5514277862471, 664.6610228783355, 664.936831001059, 665.1718610330122, 666.5467482112161, 16227.107509086996, 16276.186190690418, 16336.918315408348, 16359.672806543922, 16388.26252818626, 12669.176503527242, 13010.0, 13137.934310328386, 13423.731525369536, 13720.65698357539, 11840.467178976967, 11935.641930742091, 11954.880451195464, 11980.58067967617, 12135.635930922086, 4588.17412865684, 4599.724016559017, 4629.976850115761, 4688.085823264465, 4728.881777955544, 84911.0, 85065.0, 85557.99999999937, 85818.0, 85886.57056714738, 10787.083097936606, 10902.127829773757, 10968.945155274332, 10977.780444391226, 11140.0, 18769.81230187708, 19258.170473804967, 19284.325048623232, 21487.140514379564, 21690.783092168876, 8334.16658334174, 8502.0, 8619.094995025433, 8625.482471051757, 8721.956390218009, 4698.483166851712, 4753.881152971203, 4782.617390608743, 4981.701097934063, 4999.925001124986, 3800.771953682788, 3928.273269445162, 3982.4424580558166, 3991.4012898064943, 4008.4588580541254, 649.7790751144539, 651.6578796132069, 658.0622612776774, 663.6714826160985, 664.2925284571916, 696.6760456387804, 790.2768966395683, 800.931920786728, 810.5906517208772, 815.2703330519153, 9439.622415103318, 9735.878830855489, 9787.90212097884, 9871.40771553709, 9955.751106222402, 632.9429852146926, 697.6581475077277, 747.6261869065468, 782.8434313137282, 789.2305002622512, 4630.097131059479, 4694.741789201558, 4697.741624210701, 4727.598154156866, 4762.880927976828, 119852.0, 120768.0, 124040.0, 124276.37861810633, 125308.37345813212, 69586.65206673935, 100561.0, 105774.0, 108992.4550377243, 109819.0, 90253.0, 92442.0, 92985.0, 93125.5343723277, 93318.0, 9536.380135291183, 9595.42427454355, 9684.08001239881, 9711.98024207448, 9819.45992970365, 53422.46577534175, 54418.45581544134, 55357.72321138369, 55440.72279638576, 56365.71817140888, 79973.0, 80250.0, 80498.0, 81340.59329703313, 81368.0, 11797.17419780607, 11980.400979950793, 12077.154599177973, 12164.635060948185, 12594.370281485706, 12676.0, 13202.669933251746, 13421.597352079454, 13458.663533411744, 14089.506867259597, 13169.209847409185, 13975.860241397862, 14031.719365612835, 14077.225752583707, 14378.137311761328, 13120.540781072617, 13232.338383081, 13295.800562991757, 13812.240326782123, 13816.792748108586, 13468.932655337052, 13637.18176909349, 13669.453221871412, 13716.862831371558, 13840.653983650489, 10735.677929662123, 10765.0, 10916.0, 11082.0, 11165.609203677832, 10407.271490995558, 10821.296615719953, 10879.836802448128, 10904.672859814218, 10910.509027093642], "value": 7777.698224742522, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 110.0, 111.0, 115.0, 114.0, 111.0, 112.0, 112.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 165.0, 166.0, 128.0, 153.0, 153.0, 126.0, 178.0, 131.0], "value": 148.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 168.0, 162.0, 163.0, 164.0, 163.0, 163.0, 160.0, 163.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 165.0, 75.0, 77.0, 75.0, 75.0, 167.0, 75.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 150.0, 84.0, 86.0, 85.0, 140.0, 85.0, 87.0, 140.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 147.0, 148.0, 147.0, 147.0, 149.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 56.0, 53.0, 53.0, 53.0, 52.0, 54.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 59.0, 59.0, 59.0, 60.0, 59.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 77.0, 77.0, 77.0, 78.0, 80.0, 107.0, 77.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 116.0, 120.0, 117.0, 120.0, 130.0, 119.0, 120.0, 139.0, 121.0], "value": 122.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 157.0, 157.0, 154.0, 160.0, 164.0, 154.0, 156.0, 155.0, 157.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 63.0, 64.0, 61.0, 61.0, 71.0, 62.0], "value": 63.0, "unit": "score"}]}]} 08:20:52 INFO - Return code: 0 08:20:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:20:52 ERROR - # TBPL SUCCESS # 08:20:52 INFO - Running post-action listener: _resource_record_post_action 08:20:52 INFO - ##### 08:20:52 INFO - ##### Finished run-tests step (success) 08:20:52 INFO - ##### 08:20:52 INFO - Running post-run listener: _resource_record_post_run 08:20:52 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7925760; Write bytes: 396169216; Read time: 2052; Write time: 413776 08:20:52 INFO - TinderboxPrint: CPU usage
13.5% 08:20:52 INFO - TinderboxPrint: I/O read bytes / time
7,925,760 / 2,052 08:20:52 INFO - TinderboxPrint: I/O write bytes / time
396,169,216 / 413,776 08:20:52 INFO - TinderboxPrint: CPU idle
8,220.2 (86.5%) 08:20:52 INFO - TinderboxPrint: CPU user
1,217.5 (12.8%) 08:20:52 INFO - TinderboxPrint: Swap in / out
0 / 0 08:20:52 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 36069376; Read time: 0; Write time: 25812 08:20:52 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 6164480; Write bytes: 279187456; Read time: 1776; Write time: 245736 08:20:52 INFO - Running post-run listener: _upload_blobber_files 08:20:52 INFO - Blob upload gear active. 08:20:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:20:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:20:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:20:53 INFO - (blobuploader) - INFO - Open directory for files ... 08:20:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:20:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:54 INFO - (blobuploader) - INFO - Done attempting. 08:20:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:20:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:20:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:57 INFO - (blobuploader) - INFO - Done attempting. 08:20:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:20:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:20:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:20:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:20:58 INFO - (blobuploader) - INFO - Done attempting. 08:20:58 INFO - (blobuploader) - INFO - Iteration through files over. 08:20:58 INFO - Return code: 0 08:20:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:20:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:20:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9034677bb8f331fca21003bbc63a3fb61030a03b93f3ad6ef8d1e19f1ea89d1547104c268301ea5d8331f81b6e27ceec1944f88a197ae6a91594457db0b817fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/849e638998b27ee22a3a007acb8136d8997e6f021f52d850d61448e2409281686a316516814090837ccfd6837873a83d590db42a5d56a6258690c55f017911fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98fab7a99f6c2f5a089138e1b59f072585d9f7bba8d57257ba9eed937421c4258170a8760ffe6a6635eb6d4de34e78879e32ed69ec6e731090041adad352ff20"} 08:20:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:20:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:20:58 INFO - Contents: 08:20:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9034677bb8f331fca21003bbc63a3fb61030a03b93f3ad6ef8d1e19f1ea89d1547104c268301ea5d8331f81b6e27ceec1944f88a197ae6a91594457db0b817fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/849e638998b27ee22a3a007acb8136d8997e6f021f52d850d61448e2409281686a316516814090837ccfd6837873a83d590db42a5d56a6258690c55f017911fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98fab7a99f6c2f5a089138e1b59f072585d9f7bba8d57257ba9eed937421c4258170a8760ffe6a6635eb6d4de34e78879e32ed69ec6e731090041adad352ff20"} 08:20:58 INFO - Running post-run listener: copy_logs_to_upload_dir 08:20:58 INFO - Copying logs to upload dir... 08:20:58 INFO - mkdir: /builds/slave/test/build/upload/logs 08:20:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.947851 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-09 08:20:58.340122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 08:20:58.343564) ========= 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=52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9034677bb8f331fca21003bbc63a3fb61030a03b93f3ad6ef8d1e19f1ea89d1547104c268301ea5d8331f81b6e27ceec1944f88a197ae6a91594457db0b817fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/849e638998b27ee22a3a007acb8136d8997e6f021f52d850d61448e2409281686a316516814090837ccfd6837873a83d590db42a5d56a6258690c55f017911fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98fab7a99f6c2f5a089138e1b59f072585d9f7bba8d57257ba9eed937421c4258170a8760ffe6a6635eb6d4de34e78879e32ed69ec6e731090041adad352ff20"} build_url:https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/TyYX_8L-SiqwSb1GMqCEMA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9034677bb8f331fca21003bbc63a3fb61030a03b93f3ad6ef8d1e19f1ea89d1547104c268301ea5d8331f81b6e27ceec1944f88a197ae6a91594457db0b817fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/849e638998b27ee22a3a007acb8136d8997e6f021f52d850d61448e2409281686a316516814090837ccfd6837873a83d590db42a5d56a6258690c55f017911fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/98fab7a99f6c2f5a089138e1b59f072585d9f7bba8d57257ba9eed937421c4258170a8760ffe6a6635eb6d4de34e78879e32ed69ec6e731090041adad352ff20"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 08:20:58.368096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:20:58.368393) ========= 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=52faae308a556c440401f8e5000001e8-1468068151.303529-1518392331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004502 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 08:20:58.420036) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 08:20:58.420323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 08:20:58.423614) ========= ========= Total master_lag: 0.38 =========