builder: larch_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-067 starttime: 1464992376.36 results: success (0) buildid: 20160603125225 builduid: 1af6423d93774ecbae46eef608e782b0 revision: 07dd3ddb164c04fe57db45301de69eb3d387eab8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.363283) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.363725) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.364009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.379394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.379772) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 15:19:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2016-06-03 15:19:37 (228 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077683 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.473014) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.473341) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013973 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:36.522490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 15:19:36.524883) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 07dd3ddb164c04fe57db45301de69eb3d387eab8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 07dd3ddb164c04fe57db45301de69eb3d387eab8 --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=e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 15:19:37,516 truncating revision to first 12 chars 2016-06-03 15:19:37,516 Setting DEBUG logging. 2016-06-03 15:19:37,516 attempt 1/10 2016-06-03 15:19:37,516 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/07dd3ddb164c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 15:19:38,949 unpacking tar archive at: larch-07dd3ddb164c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.760511 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 15:19:38.301447) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:38.302054) ========= script_repo_revision: 07dd3ddb164c04fe57db45301de69eb3d387eab8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:38.303191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:38.304038) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 15:19:38.318953) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-06-03 15:19:38.319320) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] 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=e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:19:39 INFO - MultiFileLogger online at 20160603 15:19:39 in /builds/slave/test 15:19:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 15:19:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:19:39 INFO - {'append_to_log': False, 15:19:39 INFO - 'base_work_dir': '/builds/slave/test', 15:19:39 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 15:19:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:19:39 INFO - 'branch': 'Larch-Non-PGO', 15:19:39 INFO - 'buildbot_json_path': 'buildprops.json', 15:19:39 INFO - 'config_files': ('talos/linux_config.py',), 15:19:39 INFO - 'default_actions': ('clobber', 15:19:39 INFO - 'read-buildbot-config', 15:19:39 INFO - 'download-and-extract', 15:19:39 INFO - 'populate-webroot', 15:19:39 INFO - 'create-virtualenv', 15:19:39 INFO - 'install', 15:19:39 INFO - 'run-tests'), 15:19:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:19:39 INFO - 'download_minidump_stackwalk': True, 15:19:39 INFO - 'download_symbols': 'ondemand', 15:19:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:19:39 INFO - 'tooltool.py': '/tools/tooltool.py', 15:19:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:19:39 INFO - '/tools/misc-python/virtualenv.py')}, 15:19:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:19:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:19:39 INFO - 'installer_path': 'installer.exe', 15:19:39 INFO - 'log_level': 'info', 15:19:39 INFO - 'log_name': 'talos', 15:19:39 INFO - 'log_to_console': True, 15:19:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:19:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:19:39 INFO - 'opt_config_files': (), 15:19:39 INFO - 'pip_index': False, 15:19:39 INFO - 'sps_profile': False, 15:19:39 INFO - 'sps_profile_interval': 0, 15:19:39 INFO - 'suite': 'dromaeojs-e10s', 15:19:39 INFO - 'system_bits': '32', 15:19:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:19:39 INFO - 'title': 'talos-linux64-ix-067', 15:19:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:19:39 INFO - 'use_talos_json': True, 15:19:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:19:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:19:39 INFO - 'work_dir': 'build'} 15:19:39 INFO - ##### 15:19:39 INFO - ##### Running clobber step. 15:19:39 INFO - ##### 15:19:39 INFO - Running pre-action listener: _resource_record_pre_action 15:19:39 INFO - Running main action method: clobber 15:19:39 INFO - rmtree: /builds/slave/test/build 15:19:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:19:39 INFO - Running post-action listener: _resource_record_post_action 15:19:39 INFO - ##### 15:19:39 INFO - ##### Finished clobber step (success) 15:19:39 INFO - ##### 15:19:39 INFO - ##### 15:19:39 INFO - ##### Running read-buildbot-config step. 15:19:39 INFO - ##### 15:19:39 INFO - Running pre-action listener: _resource_record_pre_action 15:19:39 INFO - Running main action method: read_buildbot_config 15:19:39 INFO - Using buildbot properties: 15:19:39 INFO - { 15:19:39 INFO - "project": "", 15:19:39 INFO - "product": "firefox", 15:19:39 INFO - "script_repo_revision": "production", 15:19:39 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 15:19:39 INFO - "repository": "", 15:19:39 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs-e10s", 15:19:39 INFO - "buildid": "20160603125225", 15:19:39 INFO - "pgo_build": "False", 15:19:39 INFO - "basedir": "/builds/slave/test", 15:19:39 INFO - "buildnumber": 3, 15:19:39 INFO - "slavename": "talos-linux64-ix-067", 15:19:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:19:39 INFO - "platform": "ubuntu64_hw", 15:19:39 INFO - "scheduler": "tests-larch-linux64-talos", 15:19:39 INFO - "branch": "larch", 15:19:39 INFO - "repo_path": "projects/larch", 15:19:39 INFO - "revision": "07dd3ddb164c04fe57db45301de69eb3d387eab8", 15:19:39 INFO - "stage_platform": "linux64", 15:19:39 INFO - "builduid": "1af6423d93774ecbae46eef608e782b0", 15:19:39 INFO - "slavebuilddir": "test" 15:19:39 INFO - } 15:19:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:19:39 INFO - Running post-action listener: _resource_record_post_action 15:19:39 INFO - ##### 15:19:39 INFO - ##### Finished read-buildbot-config step (success) 15:19:39 INFO - ##### 15:19:39 INFO - ##### 15:19:39 INFO - ##### Running download-and-extract step. 15:19:39 INFO - ##### 15:19:39 INFO - Running pre-action listener: _resource_record_pre_action 15:19:39 INFO - Running main action method: download_and_extract 15:19:39 INFO - mkdir: /builds/slave/test/build/tests 15:19:39 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')]} 15:19:39 INFO - https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:19:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:19:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:19:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:19:40 INFO - Downloaded 1361 bytes. 15:19:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:19:40 INFO - Using the following test package requirements: 15:19:40 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:19:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:19:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:19:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'jsshell-linux-x86_64.zip'], 15:19:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:19:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:19:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:19:40 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:19:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:19:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:19:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:19:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:19:40 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')]} 15:19:40 INFO - https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:19:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:19:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:19:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:19:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:19:41 INFO - Downloaded 22869115 bytes. 15:19:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:19:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:19:42 INFO - Return code: 0 15:19:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:42 INFO - https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:19:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:19:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:19:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:19:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:19:43 INFO - Downloaded 22869115 bytes. 15:19:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:19:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:19:45 INFO - Return code: 0 15:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:45 INFO - https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:19:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:19:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:19:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:19:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:19:46 INFO - Downloaded 10833377 bytes. 15:19:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:19:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:19:46 INFO - Return code: 0 15:19: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')]} 15:19:46 INFO - https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:19:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:19:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:19:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:19:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:19:47 INFO - Downloaded 55245142 bytes. 15:19:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:19:47 INFO - mkdir: /builds/slave/test/properties 15:19:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:19:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:19:47 INFO - Contents: 15:19:47 INFO - build_url:https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:19:48 INFO - Running post-action listener: _resource_record_post_action 15:19:48 INFO - Running post-action listener: set_extra_try_arguments 15:19:48 INFO - ##### 15:19:48 INFO - ##### Finished download-and-extract step (success) 15:19:48 INFO - ##### 15:19:48 INFO - ##### 15:19:48 INFO - ##### Running populate-webroot step. 15:19:48 INFO - ##### 15:19:48 INFO - Running pre-action listener: _resource_record_pre_action 15:19:48 INFO - Running main action method: populate_webroot 15:19:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:19:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:19:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:19:48 INFO - '--tppagecycles', 15:19:48 INFO - '7'], 15:19:48 INFO - 'tests': ['tsvgm']}}, 15:19:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:19:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:19:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:19:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:19:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['damp', 'tps']}, 15:19:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'tests': ['damp', 'tps']}, 15:19:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['dromaeo_dom']}, 15:19:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:19:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['a11yr', 15:19:48 INFO - 'ts_paint', 15:19:48 INFO - 'tpaint', 15:19:48 INFO - 'sessionrestore', 15:19:48 INFO - 'sessionrestore_no_auto_restore', 15:19:48 INFO - 'tabpaint']}, 15:19:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:19:48 INFO - 'ts_paint', 15:19:48 INFO - 'tpaint', 15:19:48 INFO - 'sessionrestore', 15:19:48 INFO - 'sessionrestore_no_auto_restore', 15:19:48 INFO - 'tabpaint']}, 15:19:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:19:48 INFO - 'ts_paint', 15:19:48 INFO - 'tpaint', 15:19:48 INFO - 'sessionrestore', 15:19:48 INFO - 'sessionrestore_no_auto_restore', 15:19:48 INFO - 'tabpaint']}, 15:19:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:19:48 INFO - 'ts_paint', 15:19:48 INFO - 'tpaint', 15:19:48 INFO - 'sessionrestore', 15:19:48 INFO - 'sessionrestore_no_auto_restore', 15:19:48 INFO - 'tabpaint']}, 15:19:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['a11yr', 15:19:48 INFO - 'ts_paint', 15:19:48 INFO - 'tpaint', 15:19:48 INFO - 'sessionrestore', 15:19:48 INFO - 'sessionrestore_no_auto_restore', 15:19:48 INFO - 'tabpaint']}, 15:19:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['a11yr', 15:19:48 INFO - 'ts_paint', 15:19:48 INFO - 'tpaint', 15:19:48 INFO - 'sessionrestore', 15:19:48 INFO - 'sessionrestore_no_auto_restore', 15:19:48 INFO - 'tabpaint']}, 15:19:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['tsvgx', 15:19:48 INFO - 'tsvgr_opacity', 15:19:48 INFO - 'tart', 15:19:48 INFO - 'tscrollx', 15:19:48 INFO - 'cart']}, 15:19:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:19:48 INFO - 'tsvgr_opacity', 15:19:48 INFO - 'tart', 15:19:48 INFO - 'tscrollx', 15:19:48 INFO - 'cart']}, 15:19:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'talos_options': ['--disable-e10s'], 15:19:48 INFO - 'tests': ['tp5o']}, 15:19:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'tests': ['tp5o']}, 15:19:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'talos_options': ['--disable-e10s', 15:19:48 INFO - '--xperf_path', 15:19:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:48 INFO - 'tests': ['tp5n']}, 15:19:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:48 INFO - 'talos_options': ['--xperf_path', 15:19:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:48 INFO - 'tests': ['tp5n']}}, 15:19:48 INFO - 'talos.zip': {'path': '', 15:19:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:19:48 INFO - Running post-action listener: _resource_record_post_action 15:19:48 INFO - ##### 15:19:48 INFO - ##### Finished populate-webroot step (success) 15:19:48 INFO - ##### 15:19:48 INFO - ##### 15:19:48 INFO - ##### Running create-virtualenv step. 15:19:48 INFO - ##### 15:19:48 INFO - Running pre-action listener: _resource_record_pre_action 15:19:48 INFO - Running main action method: create_virtualenv 15:19:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:19:48 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 15:19:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:19:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:19:48 INFO - Using real prefix '/usr' 15:19:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:19:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:19:51 INFO - Installing pip.................done. 15:19:51 INFO - Return code: 0 15:19:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:19:51 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')]} 15:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:51 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')]} 15:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:51 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:19:51 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 15:19:51 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 15:19:51 INFO - Using env: {'DISPLAY': ':0', 15:19:51 INFO - 'HOME': '/home/cltbld', 15:19:51 INFO - 'LANG': 'en_US.UTF-8', 15:19:51 INFO - 'LANGUAGE': 'en_US:en', 15:19:51 INFO - 'LOGNAME': 'cltbld', 15:19:51 INFO - 'MAIL': '/var/mail/cltbld', 15:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:51 INFO - 'MOZ_NO_REMOTE': '1', 15:19:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:51 INFO - 'NO_EM_RESTART': '1', 15:19:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:51 INFO - 'PWD': '/builds/slave/test', 15:19:51 INFO - 'SHELL': '/bin/bash', 15:19:51 INFO - 'SHLVL': '1', 15:19:51 INFO - 'TERM': 'linux', 15:19:51 INFO - 'TMOUT': '86400', 15:19:51 INFO - 'USER': 'cltbld', 15:19:51 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:51 INFO - '_': '/tools/buildbot/bin/python'} 15:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:51 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 15:19:51 INFO - Cleaning up... 15:19:51 INFO - Return code: 0 15:19:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:19:51 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')]} 15:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:51 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')]} 15:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:51 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:19:51 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 15:19:51 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 15:19:51 INFO - Using env: {'DISPLAY': ':0', 15:19:51 INFO - 'HOME': '/home/cltbld', 15:19:51 INFO - 'LANG': 'en_US.UTF-8', 15:19:51 INFO - 'LANGUAGE': 'en_US:en', 15:19:51 INFO - 'LOGNAME': 'cltbld', 15:19:51 INFO - 'MAIL': '/var/mail/cltbld', 15:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:51 INFO - 'MOZ_NO_REMOTE': '1', 15:19:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:51 INFO - 'NO_EM_RESTART': '1', 15:19:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:51 INFO - 'PWD': '/builds/slave/test', 15:19:51 INFO - 'SHELL': '/bin/bash', 15:19:51 INFO - 'SHLVL': '1', 15:19:51 INFO - 'TERM': 'linux', 15:19:51 INFO - 'TMOUT': '86400', 15:19:51 INFO - 'USER': 'cltbld', 15:19:51 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:51 INFO - '_': '/tools/buildbot/bin/python'} 15:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:51 INFO - Downloading/unpacking psutil>=3.1.1 15:19:51 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 15:19:51 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 15:19:51 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 15:19:51 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 15:19:51 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 15:19:51 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 15:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:19:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:53 INFO - Installing collected packages: psutil 15:19:53 INFO - Running setup.py install for psutil 15:19:53 INFO - building 'psutil._psutil_linux' extension 15:19:53 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 15:19:53 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 15:19:53 INFO - building 'psutil._psutil_posix' extension 15:19:54 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 15:19:54 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 15:19:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:54 INFO - Successfully installed psutil 15:19:54 INFO - Cleaning up... 15:19:54 INFO - Return code: 0 15:19:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:19:54 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 15:19:54 INFO - Using env: {'DISPLAY': ':0', 15:19:54 INFO - 'HOME': '/home/cltbld', 15:19:54 INFO - 'LANG': 'en_US.UTF-8', 15:19:54 INFO - 'LANGUAGE': 'en_US:en', 15:19:54 INFO - 'LOGNAME': 'cltbld', 15:19:54 INFO - 'MAIL': '/var/mail/cltbld', 15:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:54 INFO - 'MOZ_NO_REMOTE': '1', 15:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:54 INFO - 'NO_EM_RESTART': '1', 15:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:54 INFO - 'PWD': '/builds/slave/test', 15:19:54 INFO - 'SHELL': '/bin/bash', 15:19:54 INFO - 'SHLVL': '1', 15:19:54 INFO - 'TERM': 'linux', 15:19:54 INFO - 'TMOUT': '86400', 15:19:54 INFO - 'USER': 'cltbld', 15:19:54 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:54 INFO - '_': '/tools/buildbot/bin/python'} 15:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:19:56 INFO - Installing collected packages: mozsystemmonitor 15:19:56 INFO - Running setup.py install for mozsystemmonitor 15:19:56 INFO - Successfully installed mozsystemmonitor 15:19:56 INFO - Cleaning up... 15:19:56 INFO - Return code: 0 15:19:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:56 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:19:56 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 15:19:56 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 15:19:56 INFO - Using env: {'DISPLAY': ':0', 15:19:56 INFO - 'HOME': '/home/cltbld', 15:19:56 INFO - 'LANG': 'en_US.UTF-8', 15:19:56 INFO - 'LANGUAGE': 'en_US:en', 15:19:56 INFO - 'LOGNAME': 'cltbld', 15:19:56 INFO - 'MAIL': '/var/mail/cltbld', 15:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:56 INFO - 'MOZ_NO_REMOTE': '1', 15:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:56 INFO - 'NO_EM_RESTART': '1', 15:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:56 INFO - 'PWD': '/builds/slave/test', 15:19:56 INFO - 'SHELL': '/bin/bash', 15:19:56 INFO - 'SHLVL': '1', 15:19:56 INFO - 'TERM': 'linux', 15:19:56 INFO - 'TMOUT': '86400', 15:19:56 INFO - 'USER': 'cltbld', 15:19:56 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:56 INFO - '_': '/tools/buildbot/bin/python'} 15:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:56 INFO - Downloading/unpacking jsonschema==2.5.1 15:19:56 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 15:19:56 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 15:19:56 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 15:19:56 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 15:19:56 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 15:19:56 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 15:19:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:58 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:58 INFO - warning: no files found matching '*.txt' 15:19:58 INFO - no previously-included directories found matching 'build' 15:19:58 INFO - no previously-included directories found matching 'dist' 15:19:58 INFO - no previously-included directories found matching '.git*' 15:19:58 INFO - Installing collected packages: jsonschema, functools32 15:19:59 INFO - Running setup.py install for functools32 15:19:59 INFO - warning: no files found matching '*.txt' 15:19:59 INFO - no previously-included directories found matching 'build' 15:19:59 INFO - no previously-included directories found matching 'dist' 15:19:59 INFO - no previously-included directories found matching '.git*' 15:19:59 INFO - Successfully installed jsonschema functools32 15:19:59 INFO - Cleaning up... 15:19:59 INFO - Return code: 0 15:19:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:59 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')]} 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:59 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')]} 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:59 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:19:59 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 15:19:59 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 15:19:59 INFO - Using env: {'DISPLAY': ':0', 15:19:59 INFO - 'HOME': '/home/cltbld', 15:19:59 INFO - 'LANG': 'en_US.UTF-8', 15:19:59 INFO - 'LANGUAGE': 'en_US:en', 15:19:59 INFO - 'LOGNAME': 'cltbld', 15:19:59 INFO - 'MAIL': '/var/mail/cltbld', 15:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:59 INFO - 'MOZ_NO_REMOTE': '1', 15:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:59 INFO - 'NO_EM_RESTART': '1', 15:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:59 INFO - 'PWD': '/builds/slave/test', 15:19:59 INFO - 'SHELL': '/bin/bash', 15:19:59 INFO - 'SHLVL': '1', 15:19:59 INFO - 'TERM': 'linux', 15:19:59 INFO - 'TMOUT': '86400', 15:19:59 INFO - 'USER': 'cltbld', 15:19:59 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:59 INFO - '_': '/tools/buildbot/bin/python'} 15:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:59 INFO - Downloading/unpacking blobuploader==1.2.4 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:20:01 INFO - Downloading blobuploader-1.2.4.tar.gz 15:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:20:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:20:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 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 15:20:01 INFO - Downloading docopt-0.6.1.tar.gz 15:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:20:02 INFO - Installing collected packages: blobuploader, requests, docopt 15:20:02 INFO - Running setup.py install for blobuploader 15:20:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:20:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:20:02 INFO - Running setup.py install for requests 15:20:02 INFO - Running setup.py install for docopt 15:20:02 INFO - Successfully installed blobuploader requests docopt 15:20:02 INFO - Cleaning up... 15:20:02 INFO - Return code: 0 15:20:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:02 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:20:02 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 15:20:02 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 15:20:02 INFO - Using env: {'DISPLAY': ':0', 15:20:02 INFO - 'HOME': '/home/cltbld', 15:20:02 INFO - 'LANG': 'en_US.UTF-8', 15:20:02 INFO - 'LANGUAGE': 'en_US:en', 15:20:02 INFO - 'LOGNAME': 'cltbld', 15:20:02 INFO - 'MAIL': '/var/mail/cltbld', 15:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:02 INFO - 'MOZ_NO_REMOTE': '1', 15:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:02 INFO - 'NO_EM_RESTART': '1', 15:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:02 INFO - 'PWD': '/builds/slave/test', 15:20:02 INFO - 'SHELL': '/bin/bash', 15:20:02 INFO - 'SHLVL': '1', 15:20:02 INFO - 'TERM': 'linux', 15:20:02 INFO - 'TMOUT': '86400', 15:20:02 INFO - 'USER': 'cltbld', 15:20:02 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:02 INFO - '_': '/tools/buildbot/bin/python'} 15:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:02 INFO - Running setup.py (path:/tmp/pip-nIa9dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:02 INFO - Running setup.py (path:/tmp/pip-FA1JNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:02 INFO - Running setup.py (path:/tmp/pip-CDgqdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:03 INFO - Running setup.py (path:/tmp/pip-mCfnm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:03 INFO - Running setup.py (path:/tmp/pip-GYSIJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:03 INFO - Running setup.py (path:/tmp/pip-KGgufh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:03 INFO - Running setup.py (path:/tmp/pip-t6Ym8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:03 INFO - Running setup.py (path:/tmp/pip-rUNgE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:03 INFO - Running setup.py (path:/tmp/pip-DO9svT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:03 INFO - Running setup.py (path:/tmp/pip-8nWsQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:03 INFO - Running setup.py (path:/tmp/pip-pRoI0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:03 INFO - Running setup.py (path:/tmp/pip-fwGgM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:03 INFO - Running setup.py (path:/tmp/pip-GsQe_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:03 INFO - Running setup.py (path:/tmp/pip-0Ce3zS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:03 INFO - Running setup.py (path:/tmp/pip-WgcN21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:03 INFO - Running setup.py (path:/tmp/pip-3Xm0pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:04 INFO - Running setup.py (path:/tmp/pip-mh2wf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:20:04 INFO - Running setup.py install for manifestparser 15:20:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Running setup.py install for mozcrash 15:20:04 INFO - Running setup.py install for mozdebug 15:20:04 INFO - Running setup.py install for mozdevice 15:20:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Running setup.py install for mozfile 15:20:04 INFO - Running setup.py install for mozhttpd 15:20:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Running setup.py install for mozinfo 15:20:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Running setup.py install for mozInstall 15:20:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:20:04 INFO - Running setup.py install for mozleak 15:20:04 INFO - Running setup.py install for mozlog 15:20:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Running setup.py install for moznetwork 15:20:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Running setup.py install for mozprocess 15:20:05 INFO - Running setup.py install for mozprofile 15:20:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Running setup.py install for mozrunner 15:20:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Running setup.py install for mozscreenshot 15:20:05 INFO - Running setup.py install for moztest 15:20:05 INFO - Running setup.py install for mozversion 15:20:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:20:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:20:05 INFO - Cleaning up... 15:20:05 INFO - Return code: 0 15:20:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:05 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:20:05 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 15:20:05 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 15:20:05 INFO - Using env: {'DISPLAY': ':0', 15:20:05 INFO - 'HOME': '/home/cltbld', 15:20:05 INFO - 'LANG': 'en_US.UTF-8', 15:20:05 INFO - 'LANGUAGE': 'en_US:en', 15:20:05 INFO - 'LOGNAME': 'cltbld', 15:20:05 INFO - 'MAIL': '/var/mail/cltbld', 15:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:05 INFO - 'MOZ_NO_REMOTE': '1', 15:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:05 INFO - 'NO_EM_RESTART': '1', 15:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:05 INFO - 'PWD': '/builds/slave/test', 15:20:05 INFO - 'SHELL': '/bin/bash', 15:20:05 INFO - 'SHLVL': '1', 15:20:05 INFO - 'TERM': 'linux', 15:20:05 INFO - 'TMOUT': '86400', 15:20:05 INFO - 'USER': 'cltbld', 15:20:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:05 INFO - '_': '/tools/buildbot/bin/python'} 15:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:06 INFO - Running setup.py (path:/tmp/pip-vBulnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:06 INFO - Running setup.py (path:/tmp/pip-MpVLQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:06 INFO - Running setup.py (path:/tmp/pip-vCE_Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:06 INFO - Running setup.py (path:/tmp/pip-1dnfh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:06 INFO - Running setup.py (path:/tmp/pip-bhdC3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:06 INFO - Running setup.py (path:/tmp/pip-5D9nA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:06 INFO - Running setup.py (path:/tmp/pip-Z8T5Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:06 INFO - Running setup.py (path:/tmp/pip-p_BjhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:06 INFO - Running setup.py (path:/tmp/pip-9xnGOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:06 INFO - Running setup.py (path:/tmp/pip-2IolTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:06 INFO - Running setup.py (path:/tmp/pip-9txYlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:06 INFO - Running setup.py (path:/tmp/pip-FTLmHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:06 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)) 15:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:06 INFO - Running setup.py (path:/tmp/pip-vuLGgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:07 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)) 15:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:07 INFO - Running setup.py (path:/tmp/pip-bsGHo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:07 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)) 15:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:07 INFO - Running setup.py (path:/tmp/pip-B7WDAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:07 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)) 15:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:07 INFO - Running setup.py (path:/tmp/pip-xVm1qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:07 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)) 15:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:07 INFO - Running setup.py (path:/tmp/pip-20HND2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:07 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)) 15:20:07 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)) 15:20:07 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)) 15:20:07 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)) 15:20:07 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)) 15:20:07 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)) 15:20:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:07 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 15:20:07 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 15:20:07 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 15:20:07 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 15:20:07 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 15:20:07 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 15:20:09 INFO - Downloading blessings-1.6.tar.gz 15:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:20:09 INFO - Installing collected packages: blessings 15:20:09 INFO - Running setup.py install for blessings 15:20:09 INFO - Successfully installed blessings 15:20:09 INFO - Cleaning up... 15:20:09 INFO - Return code: 0 15:20:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:09 INFO - Reading from file tmpfile_stdout 15:20:09 INFO - Current package versions: 15:20:09 INFO - argparse == 1.2.1 15:20:09 INFO - blessings == 1.6 15:20:09 INFO - blobuploader == 1.2.4 15:20:09 INFO - docopt == 0.6.1 15:20:09 INFO - functools32 == 3.2.3-2 15:20:09 INFO - jsonschema == 2.5.1 15:20:09 INFO - manifestparser == 1.1 15:20:09 INFO - mozInstall == 1.12 15:20:09 INFO - mozcrash == 0.17 15:20:09 INFO - mozdebug == 0.1 15:20:09 INFO - mozdevice == 0.48 15:20:09 INFO - mozfile == 1.2 15:20:09 INFO - mozhttpd == 0.7 15:20:09 INFO - mozinfo == 0.9 15:20:09 INFO - mozleak == 0.1 15:20:09 INFO - mozlog == 3.2 15:20:09 INFO - moznetwork == 0.27 15:20:09 INFO - mozprocess == 0.22 15:20:09 INFO - mozprofile == 0.28 15:20:09 INFO - mozrunner == 6.11 15:20:09 INFO - mozscreenshot == 0.1 15:20:09 INFO - mozsystemmonitor == 0.3 15:20:09 INFO - moztest == 0.7 15:20:09 INFO - mozversion == 1.4 15:20:09 INFO - psutil == 3.1.1 15:20:09 INFO - requests == 1.2.3 15:20:09 INFO - wsgiref == 0.1.2 15:20:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:09 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:20:09 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 15:20:09 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 15:20:09 INFO - Using env: {'DISPLAY': ':0', 15:20:09 INFO - 'HOME': '/home/cltbld', 15:20:09 INFO - 'LANG': 'en_US.UTF-8', 15:20:09 INFO - 'LANGUAGE': 'en_US:en', 15:20:09 INFO - 'LOGNAME': 'cltbld', 15:20:09 INFO - 'MAIL': '/var/mail/cltbld', 15:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:09 INFO - 'MOZ_NO_REMOTE': '1', 15:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:09 INFO - 'NO_EM_RESTART': '1', 15:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:09 INFO - 'PWD': '/builds/slave/test', 15:20:09 INFO - 'SHELL': '/bin/bash', 15:20:09 INFO - 'SHLVL': '1', 15:20:09 INFO - 'TERM': 'linux', 15:20:09 INFO - 'TMOUT': '86400', 15:20:09 INFO - 'USER': 'cltbld', 15:20:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:09 INFO - '_': '/tools/buildbot/bin/python'} 15:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 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)) 15:20:09 INFO - Cleaning up... 15:20:09 INFO - Return code: 0 15:20:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:09 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 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:20:09 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 15:20:09 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 15:20:09 INFO - Using env: {'DISPLAY': ':0', 15:20:09 INFO - 'HOME': '/home/cltbld', 15:20:09 INFO - 'LANG': 'en_US.UTF-8', 15:20:09 INFO - 'LANGUAGE': 'en_US:en', 15:20:09 INFO - 'LOGNAME': 'cltbld', 15:20:09 INFO - 'MAIL': '/var/mail/cltbld', 15:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:09 INFO - 'MOZ_NO_REMOTE': '1', 15:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:09 INFO - 'NO_EM_RESTART': '1', 15:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:09 INFO - 'PWD': '/builds/slave/test', 15:20:09 INFO - 'SHELL': '/bin/bash', 15:20:09 INFO - 'SHLVL': '1', 15:20:09 INFO - 'TERM': 'linux', 15:20:09 INFO - 'TMOUT': '86400', 15:20:09 INFO - 'USER': 'cltbld', 15:20:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:09 INFO - '_': '/tools/buildbot/bin/python'} 15:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:20:10 INFO - Cleaning up... 15:20:10 INFO - Return code: 0 15:20:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44b4317f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1175350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', '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 15:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:20:10 INFO - Using env: {'DISPLAY': ':0', 15:20:10 INFO - 'HOME': '/home/cltbld', 15:20:10 INFO - 'LANG': 'en_US.UTF-8', 15:20:10 INFO - 'LANGUAGE': 'en_US:en', 15:20:10 INFO - 'LOGNAME': 'cltbld', 15:20:10 INFO - 'MAIL': '/var/mail/cltbld', 15:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:10 INFO - 'MOZ_NO_REMOTE': '1', 15:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:10 INFO - 'NO_EM_RESTART': '1', 15:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:10 INFO - 'PWD': '/builds/slave/test', 15:20:10 INFO - 'SHELL': '/bin/bash', 15:20:10 INFO - 'SHLVL': '1', 15:20:10 INFO - 'TERM': 'linux', 15:20:10 INFO - 'TMOUT': '86400', 15:20:10 INFO - 'USER': 'cltbld', 15:20:10 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:10 INFO - '_': '/tools/buildbot/bin/python'} 15:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:10 INFO - Downloading/unpacking flake8 15:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:20:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:20:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 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 15:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:20:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:20:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:20:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:20:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:20:12 INFO - no previously-included directories found matching 'docs/_build' 15:20:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:20:13 INFO - Running setup.py install for pep8 15:20:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:20:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:20:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:20:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:20:13 INFO - no previously-included directories found matching 'docs/_build' 15:20:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:20:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:20:13 INFO - Cleaning up... 15:20:13 INFO - Return code: 0 15:20:13 INFO - Running post-action listener: _resource_record_post_action 15:20:13 INFO - Running post-action listener: _start_resource_monitoring 15:20:13 INFO - Starting resource monitoring. 15:20:13 INFO - ##### 15:20:13 INFO - ##### Finished create-virtualenv step (success) 15:20:13 INFO - ##### 15:20:13 INFO - ##### 15:20:13 INFO - ##### Running install step. 15:20:13 INFO - ##### 15:20:13 INFO - Running pre-action listener: _resource_record_pre_action 15:20:13 INFO - Running main action method: install 15:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:13 INFO - Reading from file tmpfile_stdout 15:20:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:13 INFO - Reading from file tmpfile_stdout 15:20:13 INFO - Output received: 15:20:13 INFO - Usage: mozinstall [options] installer 15:20:13 INFO - Options: 15:20:13 INFO - -h, --help show this help message and exit 15:20:13 INFO - -d DEST, --destination=DEST 15:20:13 INFO - Directory to install application into. [default: 15:20:13 INFO - "/builds/slave/test"] 15:20:13 INFO - --app=APP Application being installed. [default: firefox] 15:20:13 INFO - mkdir: /builds/slave/test/build/application 15:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:20:29 INFO - Reading from file tmpfile_stdout 15:20:29 INFO - Output received: 15:20:29 INFO - /builds/slave/test/build/application/firefox/firefox 15:20:29 INFO - Running post-action listener: _resource_record_post_action 15:20:29 INFO - ##### 15:20:29 INFO - ##### Finished install step (success) 15:20:29 INFO - ##### 15:20:29 INFO - ##### 15:20:29 INFO - ##### Running run-tests step. 15:20:29 INFO - ##### 15:20:29 INFO - Running pre-action listener: _resource_record_pre_action 15:20:29 INFO - Running main action method: run_tests 15:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:20:29 INFO - Python 2.7.3 15:20:29 INFO - Return code: 0 15:20:29 INFO - grabbing minidump binary from tooltool 15:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:20:29 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 0x12041c0>, '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 0x12050c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1205550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:20:29 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 15:20:29 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 15:20:29 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 15:20:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:20:29 INFO - Return code: 0 15:20:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:20:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:20:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:20:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:20:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:20:30 INFO - Return code: 0 15:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 15:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 15:20:30 INFO - Using env: {'DISPLAY': ':0', 15:20:30 INFO - 'HOME': '/home/cltbld', 15:20:30 INFO - 'LANG': 'en_US.UTF-8', 15:20:30 INFO - 'LANGUAGE': 'en_US:en', 15:20:30 INFO - 'LOGNAME': 'cltbld', 15:20:30 INFO - 'MAIL': '/var/mail/cltbld', 15:20:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:30 INFO - 'MOZ_NO_REMOTE': '1', 15:20:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:30 INFO - 'NO_EM_RESTART': '1', 15:20:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:30 INFO - 'PWD': '/builds/slave/test', 15:20:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:30 INFO - 'SHELL': '/bin/bash', 15:20:30 INFO - 'SHLVL': '1', 15:20:30 INFO - 'TERM': 'linux', 15:20:30 INFO - 'TMOUT': '86400', 15:20:30 INFO - 'USER': 'cltbld', 15:20:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857', 15:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:30 INFO - '_': '/tools/buildbot/bin/python'} 15:20:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 15:20:30 INFO - mozversion application_buildid: 20160603125225 15:20:30 INFO - mozversion application_changeset: 07dd3ddb164c04fe57db45301de69eb3d387eab8 15:20:30 INFO - mozversion application_display_name: Nightly 15:20:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:20:30 INFO - mozversion application_name: Firefox 15:20:30 INFO - mozversion application_remotingname: firefox 15:20:30 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:20:30 INFO - mozversion application_vendor: Mozilla 15:20:30 INFO - mozversion application_version: 49.0a1 15:20:30 INFO - mozversion platform_buildid: 20160603125225 15:20:30 INFO - mozversion platform_changeset: 07dd3ddb164c04fe57db45301de69eb3d387eab8 15:20:30 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:20:30 INFO - mozversion platform_version: 49.0a1 15:20:30 INFO - using testdate: 1464992430 15:20:30 INFO - actual date: 1464992430 15:20:30 INFO - starting webserver on 'localhost:50895' 15:20:30 INFO - SUITE-START | Running 2 tests 15:20:30 INFO - TEST-START | dromaeo_css 15:20:30 INFO - operating with platform_type : linux_ 15:20:30 INFO - Initialising browser for dromaeo_css test... 15:20:30 INFO - TEST-INFO | started process 30496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc7G0fR/profile http://localhost:50895/getInfo.html) 15:20:35 INFO - PROCESS | 30496 | __metrics Screen width/height:1600/1200 15:20:35 INFO - PROCESS | 30496 | colorDepth:24 15:20:35 INFO - PROCESS | 30496 | Browser inner width/height: 1024/697 15:20:35 INFO - PROCESS | 30496 | __metrics 15:20:35 ERROR - PROCESS | 30496 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 15:20:36 INFO - TEST-INFO | 30496: exit 0 15:20:36 INFO - Browser initialized. 15:20:36 INFO - Running cycle 1/1 for dromaeo_css test... 15:20:36 INFO - TEST-INFO | started process 30615 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc7G0fR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:48 INFO - PROCESS | 30615 | RSS: Main: 168366080 15:20:48 INFO - PROCESS | 30615 | 15:21:38 INFO - PROCESS | 30615 | 1464992498137 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] 15:23:49 INFO - PROCESS | 30615 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50895/tests/dromaeo/cssquery-ext.html) 15:23:50 INFO - PROCESS | 30615 | RSS: Main: 171098112 15:23:50 INFO - PROCESS | 30615 | 15:26:52 INFO - PROCESS | 30615 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-ext.html (next: http://localhost:50895/tests/dromaeo/cssquery-jquery.html) 15:26:53 INFO - PROCESS | 30615 | RSS: Main: 170414080 15:26:53 INFO - PROCESS | 30615 | 15:29:55 INFO - PROCESS | 30615 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50895/tests/dromaeo/cssquery-mootools.html) 15:29:55 INFO - PROCESS | 30615 | RSS: Main: 171380736 15:29:55 INFO - PROCESS | 30615 | 15:32:57 INFO - PROCESS | 30615 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50895/tests/dromaeo/cssquery-prototype.html) 15:32:58 INFO - PROCESS | 30615 | RSS: Main: 168747008 15:32:58 INFO - PROCESS | 30615 | 15:36:01 INFO - PROCESS | 30615 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50895/tests/dromaeo/cssquery-yui.html) 15:36:01 INFO - PROCESS | 30615 | RSS: Main: 171065344 15:36:01 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-yui.html (next: http://localhost:50895/tests/dromaeo/cssquery-dojo.html) 15:39:04 INFO - PROCESS | 30615 | RSS: Main: 172265472 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | __start_tp_report 15:39:04 INFO - PROCESS | 30615 | _x_x_mozilla_page_load 15:39:04 INFO - PROCESS | 30615 | _x_x_mozilla_page_load_details 15:39:04 INFO - PROCESS | 30615 | |i|pagename|runs| 15:39:04 INFO - PROCESS | 30615 | |0;dojo.html;2620.4104076582767;2669.626252324675;2685.6642919635046;2700.4734076855007;2701.702812690605;4608.147492713846;4637.837675681352;4656.930146047808;4728.4798672146035;4760.785764640591;4598.379218805469;4653.976730116353;4703.106409782139;4726.432828060629;4731.8817029574275;4290.592393722599;4391.978040109795;4404.427424434819;4433.246348120827;4454.643628509712;638.2201188513301;4201.789910504478;4316.82732690692;4368.344748287751;4376.890577735566;4320.30875059992;4333.111712099012;4341.4790225172865;4364.10535840152;4366.4105345778235;4338.761368124752;4357.281048626973;4366.301391777332;4382.496012958519;4449.5327990560845;4274.914501709948;4288.378185163165;4298.054428025829;4339.7830108494445;4366.847160349373;4529.297958816389;4605.194091034055;4641.930371044437;4681.297805329194;4685.625149987993;5071.797128114871;5121.539061484485;5124.718140502271;5128.5640720538795;5179.533841954205;5075.796968121271;5092.592592592584;5113.565346945514;5119.206522988943;5150.072986862363;4745.857624271277;4751.83368582098;4766.880827979328;4775.092732380871;4816.518348165156;5053.620978426633;5236.6334356595435;5240.34495688039;5256.2115682647145;5288.524032837063;5054.368203974504;5054.7472626368535;5072.9854029193575;5097.439281679012;5132.5124113209185;5127.846164615067;5134.204198349262;5161.045206636784;5166.922496162485;5201.479852014844;4878.82924097662;5009.949900500948;5010.148274793294;5066.873328166752;5100.948990510122;4981.12830254704;4988.55103040728;5010.599152067826;5019.548240658358;5108.846734597893;4268.317069268902;4269.060806622538;4272.33778764292;4303.096349766522;4311.849085282063;3689.815509224528;3696.075981004749;3700.148965737895;3731.589525152231;3741.85032598693;4337.67467439937;4392.407025051641;4410.16206920684;4475.261581839025;4492.752898590544;5141.2545180948555;5142.97428512855;5145.639805213599;5162.277281180638;5204.089284375219;4324.459442569679;4433.802873224276;4457.331400289918;4468.039371535136;4469.21788686985;4442.133783912171;4467.597916187558;4475.664325175625;4486.2597671383855;4515.887102822456;4811.78346974387;4833.05755377698;4833.202521583899;4834.057358814997;4886.169351210303;4922.1140194765285;4990.625703072356;5060.468650791761;5105.846824595268;5128.1794912813775;4750.976245118891;4793.376861008186;4813.927791083067;4815.3258543804595;4835;5041.697498150123;5051.393832739947;5060.746962651926;5069.087564238473;5085.821996230115;4872.780724867319;5054.74726263678;5072.492750724898;5105.183170692671;5108.361454818148;4962.082014827269;4975.850724478127;4981.551660350586;4990.850274491771;5044.293798868088;4775.76121193932;4980.651354405301;4983.950160498349;4988.152014157529;5027.698338099725;4849.345338379415;4863.319135320987;4872.6589138761365;4883.731394773179;4922.581580565548;4990.775415106256;4996.525330093636;5023.57299629521;5028.1200789860895;5077.898442031064;4687.296905464208;4872.634552408655;4899.632527560376;4902.362692849907;4947.2579113133315 15:39:04 INFO - PROCESS | 30615 | |1;ext.html;546.9152281396385;594.2720167794453;599.2030599302931;603.1495591216384;623.289450026969;35370.2925941481;39606.60393396065;39753.40369894454;39773;39935;39527.604723952754;40279.19441611166;40399.79800101003;40646.593534064654;40701.79649101758;7673.232676732324;7817.101033381148;7836;7846.646900889474;7871.803204919895;7618.162002179756;7635;7663.5785031823225;7666.578338191397;7671.884921726179;7815.218478152196;7822.139564647912;7828.060632724081;7879;7886.014248218973;7740.109887362969;7744.070711514626;7784.299413052824;7801.804954876088;7827.178146294671;7590.8102297443;7715.845683086307;7735.9613201933635;7738.729144479972;7747.72882949094;30097;38057.858264252114;38096.23807523834;38208.80895595532;38518;2957.822530648168;3056.419280336729;3084.1364417963005;3159.952600710968;3171.302313491028;28084.859575702194;30256.8487157565;30617.540736888965;30776.230594235098;31235.531467028013;3363.4142693324075;3369.494575813623;3412.9488057679155;3426.6059403168456;3444.7071998880124;260982;267475;267768;268054.6597267021;269118;32856.34287314241;58260.126098108565;58903.87672862859;58955.70522147362;58982.11526827015;33872.322553548795;35561.64438355635;35600.821995889855;35682.64317356845;35768.10579735475;13437.059405841497;13510.459581616626;13544.93227533856;13650.93174534121;13664.248466334448;10233.846492302622;10267.845982310271;10273.691789246335;10300.948495257475;10329;8153.306968907709;8204.83590328202;8236.8352632947;8266.504009759434;8274.089850116483;3235.8705651773666;3238;3267.8692852285644;3273.7544684148784;3287.320833520673;922.6447817590276;927.9628814847331;945.6075728572565;956.3257903178243;999.2954966748425;27359.316017099332;28856.711432885404;28860.41267730295;28880.855595722307;29240.415191696047;996.6860438961704;1025.8666373371414;1037.8910214427528;1046.7016900183412;1064.387976913278;15160.241987900561;15214.239288035555;15241.542753717406;15479.148646824307;15697.29362178682;396934;400914;402708;404614;405244;50325.74837125864;50401.24398134031;50420.49579504231;50483;50654.24018639723;1167.0721776187952;1199.976000480003;1212.7210741529498;1212.7392610588765;1229.33001514175;27381.86309068442;28054;28746.425071498034;28749.856250718614;29257.85371073131;43600;43867.341989870816;43874.34188487239;44036;44077.77961110174;341691;344395;344734;345033;345613;26155.869220653774;26268.474630507666;26807.329816753958;26823.731762682903;26898.462030759667;43734.78132609444;43883.561164387946;43992.34011489894;44516.55483445254;44554;37252.441213382364;37280.44079338866;37340.25319493649;37533.43699844559;38352.6164738349;37066.8146659265;37373.4393984085;37822.62177378191;38066.619333807415;38765.806170968965;36513.634863652085;36589.45115823318;36898.44652330164;37431.43852842155;37756.05609859776;31701.682983169874;34105.48841767425;34444.82777586096;34651;34762.65237347594;25934.48131037331;26130.216093517225;26511.602325965512;26563.031090672335;26991.730082698923 15:39:04 INFO - PROCESS | 30615 | |2;jquery.html;375.5512162965256;379.7873191013032;381.8682554518691;386.5554612195976;394.38672863696956;15719.449819256328;16051.357945682174;16967.83032169678;16994.235259413326;17004.4048458304;10446.21653375998;10529.947350263237;10543.894561054387;10550.47247637619;10570.471476426186;4512.571305725959;4554.111948170108;4729.196036673757;4734.668573199878;4756.120117778205;4421.159979603881;4674.813007479696;4683.929741053887;4697.271922851964;4699.530046995307;4697.553732395417;4699.553542413465;4706.435227772672;4714.080754252922;4992.600591952635;5202.791888324463;5327.680339179623;5334.546563542103;5350.732463376835;5365.570754339644;4967.279744437073;5315.893682126374;5322.308099947021;5351.036813373591;5370.382406023318;11862.288262704265;12070.517179312817;12268.509259629693;12290.877091229062;12383.62849114528;1759.5689056181318;1867.4677716850633;1921.4523860699774;1958.608278344337;1969.7833238343771;8947.955260223722;12924.159929604639;13213.471461141542;13381.397837097355;13421.12762670433;1662.9002259864446;1692.2215780740873;1703.974440383383;1722.905240211788;1745.5287072490482;65018;66059.33940660628;68252.658736706;70110.99999999949;71220;8841.646334146692;9217.170454658979;10111.292209545261;10271.281010329354;10355.948220259;14218.431262749375;14261.286935653383;14323.57029289123;14350.56948291532;14357.64105897361;7116.861302191624;7174.497785154989;7198;7284.417246620258;7385.704571817175;6331.905021424683;6421.678916054272;6520.18497687789;6676.599404035774;6690.665466726644;4407.779611019436;4413.3821265022925;4432.955670443319;4440.555944405598;4514.0971805639;1869.4672018474669;1878.877872938282;1901.7147427885927;1921.5964647423918;1936.593315403781;824.114077366833;854.4019186569426;880.1550511508896;890.0387581412059;973.9513024348754;16582.668346633;16811.907226030267;17161.828381716274;17191;17369.56576085583;854.9145085491401;888.9111088891189;898.1512470715112;906.7642412972542;920.7836158502746;9327.393719408217;9336.299777516577;9634.951825240829;9700.694682718507;9793.118619324152;386574;415112;415155;415583;421902;47411.76294118507;50054.21015553565;54757;86553.56723216343;87801.56099219463;932.9906700932904;939.8966113727485;955.4267439536222;975.0347156315339;976.5605477535138;15789.921050394674;15813.920930395274;15881.761773573637;15946.68106637884;16101.355945762038;32720.672793272715;33919;36593;36892.446613301356;37983.81008094942;50059.74970125126;50310.24534632057;50463;51201;51269.48730512647;7321.450891183289;7416.851662966818;7449.106107267163;7535.623218839146;7774.416918731231;10856.185786065918;11709.414529273672;11821.82267266009;11914.404279785802;12011.75976480483;9964.601415943282;10183.473472876396;10279.486025698834;10297.691069267934;10400.531976060944;10231.48842557884;10340.741481463023;10400.427976461064;10453.425061621683;10468.26722129474;10361.792764144826;10545.367277963347;10547.947260263649;10597.205209609156;10608.151347891995;7432.814179645336;7504.662290196845;7643.159252482334;7681.807954801175;7738.961305193438;8473.5339556325;9866.013398660363;10144.898551014396;10254.692359229235;10559.313644613383 15:39:04 INFO - PROCESS | 30615 | |3;mootools.html;1409.3023953143195;1410.8730214280715;1519.4757808556044;1705.1048199695151;1721.6728821523902;6447.516436267275;7144.464165187612;7160.212376638566;7464.925350746491;7541.208173141817;7273.236310187433;7414.443916706242;7437.479376443651;7454.366378857803;7485.288897554738;2174.706414634028;2218.9889050554725;2222.799948004679;2223.54417344444;2226.6548684953777;2162.1891790578534;2163.210428193706;2169.490169810101;2171.880546569938;2204.481946742514;2173.434906924203;2180.3349978256624;2191.5178660694623;2195.1438938813876;2236.541508990661;2071.792820717924;2076.771555128935;2078.740157480315;2079.7712251652306;2090.362439455973;2077.854550181488;2081.8334533237303;2121.7029615853794;2134.879188426073;2148.355493351988;7364.889526657104;7423.369013633794;7439.55362678241;7445.813854653623;7447.2925072118605;4303.33298338758;4427.402300689398;4455.732656040615;4470.351798989161;4471.061077173765;2850.914472565826;2943.632045994251;2954.8818047278082;2954.8818047278082;2958.5562165675105;874.8950125984923;928.2573940847362;939.4504215034214;956.8516879883629;958.8993155718618;9715.36850104748;9718.076782705632;9762.121409073146;9771.560279787356;9791.804163916753;4070.2876996526084;4099;4213.367994800805;4232.788360581959;4239.364095385717;8329.5418751968;8405.24352808238;8466.915330846612;8473.067962524117;8489.108643592457;4249.043965107888;4326.480822301344;4334.956650433518;4447.510773814878;4464.955350446519;3528.788272703646;3917.666998305119;3994.6005399459823;3995.08113133975;4086.3053280942313;3590.7306951978185;3630.6006339302658;3663.4504824276037;3721.4603882436895;3738.3831667775057;1205.6563879294358;1222.0773316146253;1224.6264889208699;1231.6305108467425;1234.2594443334108;866.2853146154448;878.2482561276906;886.4814083760944;888.5113187746714;896.8744375787395;5033.546980771748;5076.974615126975;5095.159298715745;5128.589712822965;5142.1772516397195;388.2487386906345;395.58661199047066;397.68582819572794;413.34484841525915;426.923153832307;3679.7056235501095;3712.925741485155;3719.367707489733;3745.7752534847996;3751.793651349202;8465.534395608302;8749.47503149813;8752.956235218911;8755.255803256667;8759.211670949646;8642.956785216034;8659.307255419551;8684.8697269541;8694.956525217334;8724.912750872538;6818.727250909908;6832.350926661959;6878.759243426456;6885.827854303582;6926.722931082701;4953.0589188054155;4993.550580447777;5043.218301163325;5158.123119069842;5182.481751824787;8109.472884262504;8214.424990250864;8256.463329883538;8266.710665126931;8284.668613255402;7976.601169941596;8227.259546640831;8274.503529788231;8325.666973321;8333.499990000619;5619.49424551776;5893.6463812171405;6161.784337548164;6359.332270111571;6431.29255781873;7128.536645118051;7165.140183178052;7167.139943206848;7208.927910720826;7210.2789721027475;6213.2233470816145;6385.138006369267;6463.709133089116;6533.248676402274;6565.113709649137;6345.111684364285;6458.709358078804;6474.643894585843;6495.123158373748;6618.305077966744;6001.339852616296;6058.969705151622;6073.726682299395;6074.210352654303;6078.179445774764;4527.9547204527535;4560.2019646562385;4589.495155532822;4676.508966558598;4698.3657206278085;4607.93088103672;4627.884302892455;4675.462321832897;4685.465515013584;4722.1972264714395 15:39:04 INFO - PROCESS | 30615 | |4;prototype.html;297.30875713965025;303.73878464520516;307.28096254763864;308.1080272610791;308.29093085902446;4835.613150947925;4961.2310091935715;4984.202527595586;5042.848714538565;5063.2658264551665;4961.280614310932;4985.202367621181;5001.44984051754;5005.699658020521;5032.773525191366;3926.27363937671;3934.114824164554;3940.556854750059;3951.288768021755;3953.308171070066;3629.2560025194903;3959.3269144245546;3966.345552983769;3972.304846651839;3974.8012599369918;3771.3023090728166;3813.3707938190164;3817.7019813263487;3821.178446633973;3832.310184166849;3794.886153415388;3807.124361396894;3812.3519001769764;3827.923441531154;3857.1321452672923;3341.195754292679;3349.447341188701;3350.6481819408855;3372.460406334975;3375.4599264117624;4709.576138147549;4755.690880092783;4780.139574876521;4845.224764037737;4849.951500485021;4331.371951067109;4345.9348109778375;4350.6736994725525;4354.390385346054;4362.716423432498;5173;5205.334293026233;5212.739363031871;5229.346331708536;5247.556921846484;3552.6802587766965;3648.416253399443;3659.6157403472516;3660.3411385950517;3660.762050466738;5310.70791106489;5482.150266708666;5503.422140675211;5512.283403157604;5533.916991245094;3656.9817150914073;3672.908177295589;3687.520622319081;3690.815459227028;3699.1491956850077;3783.3000894834545;3792.9569368423904;3796.3166630006863;3797.9240415191543;3798.430235464647;4226.978865105716;4282.73659270516;4292.635126014324;4305.461817272841;4346.2828633275785;5192.792288308501;5211.296474975906;5226.163813789775;5226.346706661668;5229.424763276036;1217.0567809947186;1265.9177153484993;1285.9164154330126;1292.38611659461;1296.9610911672664;806.5563939833069;811.8619834628128;830.3938125168661;830.9169083091642;832.8126171611339;4110.533679792101;4124.845043387856;4168.6665066794585;4195.7692326922315;4199.958000420018;4772.832950846704;4790.012144959872;4810.811080958156;4836.685615435056;4837.4920633333695;4084.4077608746566;4132.210757738575;4142.964258935266;4163.439484716938;4208;2536.7823444746255;2648.3776312566533;2901.2166714986834;2906.735570027045;2909.418116376733;5129.3075434816565;5136.512031357015;5136.5890728741615;5153.639245252872;5178.378594568676;5354.973225133849;5531.2809334787025;5547.667139971614;5553.666779993213;5553.805616803474;5123.231515272664;5137.563307118862;5143.717095559706;5148.665336753099;5157.355330583677;5415.214793854948;5451.046066938349;5463.48096930791;5463.617546771847;5477.534409575231;5081.822136225214;5325.786968521215;5354.919676204782;5363.27595774581;5364.275822763877;5139.152039913522;5149.407818100966;5173.1205695031185;5193.714345711022;5200.297959775382;3786.280606684721;3809.028697682184;3812.5424949005187;3817.8281977311644;3834.271488417192;5120.513551212629;5176.559992400537;5441.5374693151525;5466.234727138283;5479.931413374354;5141.177411614124;5227.843164705065;5240.34495688039;5246.501582349671;5271.156614941591;5232.9738351308;5253.868653283698;5256.030342856274;5285.674303306687;5338.306020217502;4968.180250258667;5209.843704688866;5219.2953951216095;5241.344831896013;5243.767714586993;5306.708131052829;5308.867278318073;5319.9468005321;5323.574114070942;5359.249705041375;5182.429932707475;5213.921791173211;5216.869578260574;5245.186996015624;5247.4490178530705 15:39:04 INFO - PROCESS | 30615 | |5;yui.html;628.8585068359619;675.6317806795296;701.4423533291033;709.0180100560722;728.5701436152669;12455.564055258068;12635.747285054293;12776.42506087226;12983.805242921364;13396;12679.556215532435;12711.936440317808;12736.80894786579;12793;12826.615201543946;614.9354317796634;773.787208517658;775.6897241103547;797.0714118052462;809.4900212865887;788.8422315536886;800.6837299266787;801.4229754576695;801.6833350826449;801.9719309824158;827.6523859978794;828.5857071464268;829.6930135849744;832.8292699996487;833.8207285433631;822.1737154160061;823.3454403749034;823.3536673711138;824.1758241758242;824.7649419915325;606.6875559087074;606.7330374635146;607.5413063137303;608.5618354784548;610.5664977865721;12870.16343937641;12887.484500619965;12887.67780805478;12893;12922;12128.696782580419;12581.811272830915;12651.367431628474;12785.808212876815;12794;10169.03394177552;10247.487625618689;10325.793484130276;10523.631672891412;10749.247552671317;3272.754543409254;3282.0646115857103;3288.901332960015;3311.817850018248;3368.6462921393145;57463.425365746225;59561.702191489196;60871.391286087455;61049.38950610482;61499.6925015372;8596.226339629464;8803.515806630698;8923.687670931617;9021.729348119567;9177.219936305355;19212;19280.807191927903;19503.122359493856;19537.609247814966;19749.703754443693;6570.737170513222;6582.868342633216;6707.765228216989;6818.863622727517;6821.897671534931;3604.6034936157002;3609.187932715118;3660.7803531787645;3674.356987527172;3718.6839118675216;2891.6530016398165;2980.344324248662;3009.337945651953;3103.1001009707384;3251.674832516777;505.4288653821159;528.0310629993927;539.7409243563112;578.8321386797866;588.9722434351997;592.1288727620698;610.2646311194999;622.5424313129846;627.6830200748593;630.7886857902654;8592.827736719384;8593.226609605166;8719.694810681722;8737.388382813142;9054.139856713602;567.4637467593085;583.2010146099785;604.5465900574569;606.6572386601556;613.4693490131004;4179.9791001045405;4265.573442655709;4280.743155410685;4282.336237883133;4315.5900189481945;94811.52594236984;98483.50758246164;101745.49127254316;103658;103851;53480.19779703308;54135;54328;64856.35143648597;77572.61213693896;49141;58589;76436.61781691168;76508;77361.61319193368;7996.84006319882;8123.634436450492;8176.46852954556;8304;8375.37184711137;45926.54073459223;47007;47451;48218.5178148214;48897.511024889296;66084.66957665181;68563;69051;70160;70721.64639176772;9660.661876834278;9747.707568772948;9985.550650220903;10062;10123.848142278019;11217.887821121685;11258.169201549292;11351.54593816238;11377.943110284727;11465.598704045318;10641.574337026432;10698.197635177237;10758.462076895967;10864.185186111232;11265.662030138781;10897.291676041032;11001.559937602406;11044.723881902695;11110;11138.665840024812;9999;10686.679399618024;10782.622608208674;10879.401632910265;10938.671839844817;8563.486190828571;9310.115539023782;9547.985344353021;9884.654037108667;9910.702678919355;9005.234555062892;9105.408148470327;9116.726498205064;9263.444193348421;9268.907310926805 15:39:04 INFO - PROCESS | 30615 | __end_tp_report 15:39:04 INFO - PROCESS | 30615 | __start_cc_report 15:39:04 INFO - PROCESS | 30615 | _x_x_mozilla_cycle_collect,385 15:39:04 INFO - PROCESS | 30615 | __end_cc_report 15:39:04 INFO - PROCESS | 30615 | __startTimestamp1464993544496__endTimestamp 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | ------- Summary: start ------- 15:39:04 INFO - PROCESS | 30615 | Number of tests: 6 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | [#0] dojo.html Cycles:165 Average:4671.36 Median:4833.20 stddev:591.82 (12.2%) stddev-sans-first:571.34 15:39:04 INFO - PROCESS | 30615 | Values: 2620.4 2669.6 2685.7 2700.5 2701.7 4608.1 4637.8 4656.9 4728.5 4760.8 4598.4 4654.0 4703.1 4726.4 4731.9 4290.6 4392.0 4404.4 4433.2 4454.6 638.2 4201.8 4316.8 4368.3 4376.9 4320.3 4333.1 4341.5 4364.1 4366.4 4338.8 4357.3 4366.3 4382.5 4449.5 4274.9 4288.4 4298.1 4339.8 4366.8 4529.3 4605.2 4641.9 4681.3 4685.6 5071.8 5121.5 5124.7 5128.6 5179.5 5075.8 5092.6 5113.6 5119.2 5150.1 4745.9 4751.8 4766.9 4775.1 4816.5 5053.6 5236.6 5240.3 5256.2 5288.5 5054.4 5054.7 5073.0 5097.4 5132.5 5127.8 5134.2 5161.0 5166.9 5201.5 4878.8 5009.9 5010.1 5066.9 5100.9 4981.1 4988.6 5010.6 5019.5 5108.8 4268.3 4269.1 4272.3 4303.1 4311.8 3689.8 3696.1 3700.1 3731.6 3741.9 4337.7 4392.4 4410.2 4475.3 4492.8 5141.3 5143.0 5145.6 5162.3 5204.1 4324.5 4433.8 4457.3 4468.0 4469.2 4442.1 4467.6 4475.7 4486.3 4515.9 4811.8 4833.1 4833.2 4834.1 4886.2 4922.1 4990.6 5060.5 5105.8 5128.2 4751.0 4793.4 4813.9 4815.3 4835.0 5041.7 5051.4 5060.7 5069.1 5085.8 4872.8 5054.7 5072.5 5105.2 5108.4 4962.1 4975.9 4981.6 4990.9 5044.3 4775.8 4980.7 4984.0 4988.2 5027.7 4849.3 4863.3 4872.7 4883.7 4922.6 4990.8 4996.5 5023.6 5028.1 5077.9 4687.3 4872.6 4899.6 4902.4 4947.3 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | [#1] ext.html Cycles:180 Average:48405.70 Median:37159.63 stddev:90370.77 (243.2%) stddev-sans-first:90552.85 15:39:04 INFO - PROCESS | 30615 | Values: 546.9 594.3 599.2 603.1 623.3 35370.3 39606.6 39753.4 39773.0 39935.0 39527.6 40279.2 40399.8 40646.6 40701.8 7673.2 7817.1 7836.0 7846.6 7871.8 7618.2 7635.0 7663.6 7666.6 7671.9 7815.2 7822.1 7828.1 7879.0 7886.0 7740.1 7744.1 7784.3 7801.8 7827.2 7590.8 7715.8 7736.0 7738.7 7747.7 30097.0 38057.9 38096.2 38208.8 38518.0 2957.8 3056.4 3084.1 3160.0 3171.3 28084.9 30256.8 30617.5 30776.2 31235.5 3363.4 3369.5 3412.9 3426.6 3444.7 260982.0 267475.0 267768.0 268054.7 269118.0 32856.3 58260.1 58903.9 58955.7 58982.1 33872.3 35561.6 35600.8 35682.6 35768.1 13437.1 13510.5 13544.9 13650.9 13664.2 10233.8 10267.8 10273.7 10300.9 10329.0 8153.3 8204.8 8236.8 8266.5 8274.1 3235.9 3238.0 3267.9 3273.8 3287.3 922.6 928.0 945.6 956.3 999.3 27359.3 28856.7 28860.4 28880.9 29240.4 996.7 1025.9 1037.9 1046.7 1064.4 15160.2 15214.2 15241.5 15479.1 15697.3 396934.0 400914.0 402708.0 404614.0 405244.0 50325.7 50401.2 50420.5 50483.0 50654.2 1167.1 1200.0 1212.7 1212.7 1229.3 27381.9 28054.0 28746.4 28749.9 29257.9 43600.0 43867.3 43874.3 44036.0 44077.8 341691.0 344395.0 344734.0 345033.0 345613.0 26155.9 26268.5 26807.3 26823.7 26898.5 43734.8 43883.6 43992.3 44516.6 44554.0 37252.4 37280.4 37340.3 37533.4 38352.6 37066.8 37373.4 37822.6 38066.6 38765.8 36513.6 36589.5 36898.4 37431.4 37756.1 31701.7 34105.5 34444.8 34651.0 34762.7 25934.5 26130.2 26511.6 26563.0 26991.7 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | [#2] jquery.html Cycles:180 Average:24110.87 Median:4417.27 stddev:67612.57 (1530.6%) stddev-sans-first:67778.75 15:39:04 INFO - PROCESS | 30615 | Values: 375.6 379.8 381.9 386.6 394.4 15719.4 16051.4 16967.8 16994.2 17004.4 10446.2 10529.9 10543.9 10550.5 10570.5 4512.6 4554.1 4729.2 4734.7 4756.1 4421.2 4674.8 4683.9 4697.3 4699.5 4697.6 4699.6 4706.4 4714.1 4992.6 5202.8 5327.7 5334.5 5350.7 5365.6 4967.3 5315.9 5322.3 5351.0 5370.4 11862.3 12070.5 12268.5 12290.9 12383.6 1759.6 1867.5 1921.5 1958.6 1969.8 8948.0 12924.2 13213.5 13381.4 13421.1 1662.9 1692.2 1704.0 1722.9 1745.5 65018.0 66059.3 68252.7 70111.0 71220.0 8841.6 9217.2 10111.3 10271.3 10355.9 14218.4 14261.3 14323.6 14350.6 14357.6 7116.9 7174.5 7198.0 7284.4 7385.7 6331.9 6421.7 6520.2 6676.6 6690.7 4407.8 4413.4 4433.0 4440.6 4514.1 1869.5 1878.9 1901.7 1921.6 1936.6 824.1 854.4 880.2 890.0 974.0 16582.7 16811.9 17161.8 17191.0 17369.6 854.9 888.9 898.2 906.8 920.8 9327.4 9336.3 9635.0 9700.7 9793.1 386574.0 415112.0 415155.0 415583.0 421902.0 47411.8 50054.2 54757.0 86553.6 87801.6 933.0 939.9 955.4 975.0 976.6 15789.9 15813.9 15881.8 15946.7 16101.4 32720.7 33919.0 36593.0 36892.4 37983.8 50059.7 50310.2 50463.0 51201.0 51269.5 7321.5 7416.9 7449.1 7535.6 7774.4 10856.2 11709.4 11821.8 11914.4 12011.8 9964.6 10183.5 10279.5 10297.7 10400.5 10231.5 10340.7 10400.4 10453.4 10468.3 10361.8 10545.4 10547.9 10597.2 10608.2 7432.8 7504.7 7643.2 7681.8 7739.0 8473.5 9866.0 10144.9 10254.7 10559.3 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | [#3] mootools.html Cycles:180 Average:4875.19 Median:5111.87 stddev:2616.97 (51.2%) stddev-sans-first:2611.35 15:39:04 INFO - PROCESS | 30615 | Values: 1409.3 1410.9 1519.5 1705.1 1721.7 6447.5 7144.5 7160.2 7464.9 7541.2 7273.2 7414.4 7437.5 7454.4 7485.3 2174.7 2219.0 2222.8 2223.5 2226.7 2162.2 2163.2 2169.5 2171.9 2204.5 2173.4 2180.3 2191.5 2195.1 2236.5 2071.8 2076.8 2078.7 2079.8 2090.4 2077.9 2081.8 2121.7 2134.9 2148.4 7364.9 7423.4 7439.6 7445.8 7447.3 4303.3 4427.4 4455.7 4470.4 4471.1 2850.9 2943.6 2954.9 2954.9 2958.6 874.9 928.3 939.5 956.9 958.9 9715.4 9718.1 9762.1 9771.6 9791.8 4070.3 4099.0 4213.4 4232.8 4239.4 8329.5 8405.2 8466.9 8473.1 8489.1 4249.0 4326.5 4335.0 4447.5 4465.0 3528.8 3917.7 3994.6 3995.1 4086.3 3590.7 3630.6 3663.5 3721.5 3738.4 1205.7 1222.1 1224.6 1231.6 1234.3 866.3 878.2 886.5 888.5 896.9 5033.5 5077.0 5095.2 5128.6 5142.2 388.2 395.6 397.7 413.3 426.9 3679.7 3712.9 3719.4 3745.8 3751.8 8465.5 8749.5 8753.0 8755.3 8759.2 8643.0 8659.3 8684.9 8695.0 8724.9 6818.7 6832.4 6878.8 6885.8 6926.7 4953.1 4993.6 5043.2 5158.1 5182.5 8109.5 8214.4 8256.5 8266.7 8284.7 7976.6 8227.3 8274.5 8325.7 8333.5 5619.5 5893.6 6161.8 6359.3 6431.3 7128.5 7165.1 7167.1 7208.9 7210.3 6213.2 6385.1 6463.7 6533.2 6565.1 6345.1 6458.7 6474.6 6495.1 6618.3 6001.3 6059.0 6073.7 6074.2 6078.2 4528.0 4560.2 4589.5 4676.5 4698.4 4607.9 4627.9 4675.5 4685.5 4722.2 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | [#4] prototype.html Cycles:180 Average:4295.25 Median:4905.59 stddev:1280.18 (26.1%) stddev-sans-first:1248.11 15:39:04 INFO - PROCESS | 30615 | Values: 297.3 303.7 307.3 308.1 308.3 4835.6 4961.2 4984.2 5042.8 5063.3 4961.3 4985.2 5001.4 5005.7 5032.8 3926.3 3934.1 3940.6 3951.3 3953.3 3629.3 3959.3 3966.3 3972.3 3974.8 3771.3 3813.4 3817.7 3821.2 3832.3 3794.9 3807.1 3812.4 3827.9 3857.1 3341.2 3349.4 3350.6 3372.5 3375.5 4709.6 4755.7 4780.1 4845.2 4850.0 4331.4 4345.9 4350.7 4354.4 4362.7 5173.0 5205.3 5212.7 5229.3 5247.6 3552.7 3648.4 3659.6 3660.3 3660.8 5310.7 5482.2 5503.4 5512.3 5533.9 3657.0 3672.9 3687.5 3690.8 3699.1 3783.3 3793.0 3796.3 3797.9 3798.4 4227.0 4282.7 4292.6 4305.5 4346.3 5192.8 5211.3 5226.2 5226.3 5229.4 1217.1 1265.9 1285.9 1292.4 1297.0 806.6 811.9 830.4 830.9 832.8 4110.5 4124.8 4168.7 4195.8 4200.0 4772.8 4790.0 4810.8 4836.7 4837.5 4084.4 4132.2 4143.0 4163.4 4208.0 2536.8 2648.4 2901.2 2906.7 2909.4 5129.3 5136.5 5136.6 5153.6 5178.4 5355.0 5531.3 5547.7 5553.7 5553.8 5123.2 5137.6 5143.7 5148.7 5157.4 5415.2 5451.0 5463.5 5463.6 5477.5 5081.8 5325.8 5354.9 5363.3 5364.3 5139.2 5149.4 5173.1 5193.7 5200.3 3786.3 3809.0 3812.5 3817.8 3834.3 5120.5 5176.6 5441.5 5466.2 5479.9 5141.2 5227.8 5240.3 5246.5 5271.2 5233.0 5253.9 5256.0 5285.7 5338.3 4968.2 5209.8 5219.3 5241.3 5243.8 5306.7 5308.9 5319.9 5323.6 5359.2 5182.4 5213.9 5216.9 5245.2 5247.4 15:39:04 INFO - PROCESS | 30615 | 15:39:04 INFO - PROCESS | 30615 | [#5] yui.html Cycles:180 Average:16997.13 Median:29588.99 stddev:24249.23 (82.0%) stddev-sans-first:24286.11 15:39:04 INFO - PROCESS | 30615 | Values: 628.9 675.6 701.4 709.0 728.6 12455.6 12635.7 12776.4 12983.8 13396.0 12679.6 12711.9 12736.8 12793.0 12826.6 614.9 773.8 775.7 797.1 809.5 788.8 800.7 801.4 801.7 802.0 827.7 828.6 829.7 832.8 833.8 822.2 823.3 823.4 824.2 824.8 606.7 606.7 607.5 608.6 610.6 12870.2 12887.5 12887.7 12893.0 12922.0 12128.7 12581.8 12651.4 12785.8 12794.0 10169.0 10247.5 10325.8 10523.6 10749.2 3272.8 3282.1 3288.9 3311.8 3368.6 57463.4 59561.7 60871.4 61049.4 61499.7 8596.2 8803.5 8923.7 9021.7 9177.2 19212.0 19280.8 19503.1 19537.6 19749.7 6570.7 6582.9 6707.8 6818.9 6821.9 3604.6 3609.2 3660.8 3674.4 3718.7 2891.7 2980.3 3009.3 3103.1 3251.7 505.4 528.0 539.7 578.8 589.0 592.1 610.3 622.5 627.7 630.8 8592.8 8593.2 8719.7 8737.4 9054.1 567.5 583.2 604.5 606.7 613.5 4180.0 4265.6 4280.7 4282.3 4315.6 94811.5 98483.5 101745.5 103658.0 103851.0 53480.2 54135.0 54328.0 64856.4 77572.6 49141.0 58589.0 76436.6 76508.0 77361.6 7996.8 8123.6 8176.5 8304.0 8375.4 45926.5 47007.0 47451.0 48218.5 48897.5 66084.7 68563.0 69051.0 70160.0 70721.6 9660.7 9747.7 9985.6 10062.0 10123.8 11217.9 11258.2 11351.5 11377.9 11465.6 10641.6 10698.2 10758.5 10864.2 11265.7 10897.3 11001.6 11044.7 11110.0 11138.7 9999.0 10686.7 10782.6 10879.4 10938.7 8563.5 9310.1 9548.0 9884.7 9910.7 9005.2 9105.4 9116.7 9263.4 9268.9 15:39:04 INFO - PROCESS | 30615 | -------- Summary: end -------- 15:39:04 INFO - PROCESS | 30615 | 15:39:05 INFO - TEST-INFO | 30615: exit 0 15:39:05 INFO - TEST-OK | dromaeo_css | took 1114590ms 15:39:05 INFO - TEST-START | kraken 15:39:05 INFO - operating with platform_type : linux_ 15:39:05 INFO - Initialising browser for kraken test... 15:39:05 INFO - TEST-INFO | started process 30859 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOKsnF9/profile http://localhost:50895/getInfo.html) 15:39:09 INFO - PROCESS | 30859 | __metrics Screen width/height:1600/1200 15:39:09 INFO - PROCESS | 30859 | colorDepth:24 15:39:09 INFO - PROCESS | 30859 | Browser inner width/height: 1024/697 15:39:09 INFO - PROCESS | 30859 | __metrics 15:39:10 ERROR - PROCESS | 30859 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 15:39:10 INFO - TEST-INFO | 30859: exit 0 15:39:10 INFO - Browser initialized. 15:39:10 INFO - Running cycle 1/1 for kraken test... 15:39:10 INFO - TEST-INFO | started process 30979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOKsnF9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:23 INFO - PROCESS | 30979 | RSS: Main: 168853504 15:39:23 INFO - PROCESS | 30979 | 15:39:25 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-beat-detection) 15:39:26 INFO - PROCESS | 30979 | RSS: Main: 172429312 15:39:26 INFO - PROCESS | 30979 | 15:39:29 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-dft) 15:39:30 INFO - PROCESS | 30979 | RSS: Main: 172953600 15:39:30 INFO - PROCESS | 30979 | 15:39:33 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-fft) 15:39:33 INFO - PROCESS | 30979 | RSS: Main: 167809024 15:39:33 INFO - PROCESS | 30979 | 15:39:35 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-oscillator) 15:39:36 INFO - PROCESS | 30979 | RSS: Main: 168624128 15:39:36 INFO - PROCESS | 30979 | 15:39:38 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50895/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:39 INFO - PROCESS | 30979 | RSS: Main: 168751104 15:39:39 INFO - PROCESS | 30979 | 15:39:42 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50895/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:43 INFO - PROCESS | 30979 | RSS: Main: 168857600 15:39:43 INFO - PROCESS | 30979 | 15:39:46 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50895/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:47 INFO - PROCESS | 30979 | RSS: Main: 166809600 15:39:47 INFO - PROCESS | 30979 | 15:39:50 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50895/tests/kraken/driver.html?testName=json-parse-financial) 15:39:50 INFO - PROCESS | 30979 | RSS: Main: 168210432 15:39:50 INFO - PROCESS | 30979 | 15:39:52 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50895/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:53 INFO - PROCESS | 30979 | RSS: Main: 168914944 15:39:53 INFO - PROCESS | 30979 | 15:39:55 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:55 INFO - PROCESS | 30979 | RSS: Main: 169197568 15:39:55 INFO - PROCESS | 30979 | 15:39:58 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:58 INFO - PROCESS | 30979 | RSS: Main: 169644032 15:39:58 INFO - PROCESS | 30979 | 15:40:01 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:40:02 INFO - PROCESS | 30979 | RSS: Main: 169185280 15:40:02 INFO - PROCESS | 30979 | 15:40:05 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:40:05 INFO - PROCESS | 30979 | RSS: Main: 168960000 15:40:05 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50895/tests/kraken/driver.html?testName=ai-astar) 15:40:07 INFO - PROCESS | 30979 | RSS: Main: 169947136 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | __start_tp_report 15:40:07 INFO - PROCESS | 30979 | _x_x_mozilla_page_load 15:40:07 INFO - PROCESS | 30979 | _x_x_mozilla_page_load_details 15:40:07 INFO - PROCESS | 30979 | |i|pagename|runs| 15:40:07 INFO - PROCESS | 30979 | |0;ai-astar;115;108;113;116;113;114;114;114;112;113 15:40:07 INFO - PROCESS | 30979 | |1;audio-beat-detection;168;138;157;184;132;160;163;132;191;135 15:40:07 INFO - PROCESS | 30979 | |2;audio-dft;164;164;165;177;162;163;163;164;163;162 15:40:07 INFO - PROCESS | 30979 | |3;audio-fft;77;77;77;153;76;75;75;76;161;77 15:40:07 INFO - PROCESS | 30979 | |4;audio-oscillator;87;86;143;88;88;88;141;86;86;131 15:40:07 INFO - PROCESS | 30979 | |5;imaging-gaussian-blur;118;118;118;117;118;118;118;118;119;117 15:40:07 INFO - PROCESS | 30979 | |6;imaging-darkroom;148;149;147;150;146;148;146;147;149;150 15:40:07 INFO - PROCESS | 30979 | |7;imaging-desaturate;96;96;96;96;95;96;95;95;96;95 15:40:07 INFO - PROCESS | 30979 | |8;json-parse-financial;65;62;66;64;64;63;63;63;65;65 15:40:07 INFO - PROCESS | 30979 | |9;json-stringify-tinderbox;66;65;72;66;66;66;66;66;71;67 15:40:07 INFO - PROCESS | 30979 | |10;stanford-crypto-aes;87;82;84;83;90;86;87;85;119;84 15:40:07 INFO - PROCESS | 30979 | |11;stanford-crypto-ccm;137;139;137;139;141;132;134;139;150;137 15:40:07 INFO - PROCESS | 30979 | |12;stanford-crypto-pbkdf2;170;173;173;173;174;173;176;170;169;173 15:40:07 INFO - PROCESS | 30979 | |13;stanford-crypto-sha256-iterative;68;68;69;70;69;67;68;68;67;66 15:40:07 INFO - PROCESS | 30979 | __end_tp_report 15:40:07 INFO - PROCESS | 30979 | __start_cc_report 15:40:07 INFO - PROCESS | 30979 | _x_x_mozilla_cycle_collect,980 15:40:07 INFO - PROCESS | 30979 | __end_cc_report 15:40:07 INFO - PROCESS | 30979 | __startTimestamp1464993607730__endTimestamp 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | ------- Summary: start ------- 15:40:07 INFO - PROCESS | 30979 | Number of tests: 14 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:2.15 (1.9%) stddev-sans-first:2.18 15:40:07 INFO - PROCESS | 30979 | Values: 115.0 108.0 113.0 116.0 113.0 114.0 114.0 114.0 112.0 113.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#1] audio-beat-detection Cycles:10 Average:156.00 Median:161.50 stddev:21.44 (13.3%) stddev-sans-first:22.29 15:40:07 INFO - PROCESS | 30979 | Values: 168.0 138.0 157.0 184.0 132.0 160.0 163.0 132.0 191.0 135.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#2] audio-dft Cycles:10 Average:164.70 Median:164.00 stddev:4.42 (2.7%) stddev-sans-first:4.68 15:40:07 INFO - PROCESS | 30979 | Values: 164.0 164.0 165.0 177.0 162.0 163.0 163.0 164.0 163.0 162.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#3] audio-fft Cycles:10 Average:92.40 Median:76.50 stddev:34.11 (44.6%) stddev-sans-first:35.72 15:40:07 INFO - PROCESS | 30979 | Values: 77.0 77.0 77.0 153.0 76.0 75.0 75.0 76.0 161.0 77.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:86.50 stddev:24.99 (28.9%) stddev-sans-first:25.88 15:40:07 INFO - PROCESS | 30979 | Values: 87.0 86.0 143.0 88.0 88.0 88.0 141.0 86.0 86.0 131.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:40:07 INFO - PROCESS | 30979 | Values: 118.0 118.0 118.0 117.0 118.0 118.0 118.0 118.0 119.0 117.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.50 stddev:1.49 (1.0%) stddev-sans-first:1.58 15:40:07 INFO - PROCESS | 30979 | Values: 148.0 149.0 147.0 150.0 146.0 148.0 146.0 147.0 149.0 150.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:40:07 INFO - PROCESS | 30979 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.50 stddev:1.25 (1.9%) stddev-sans-first:1.27 15:40:07 INFO - PROCESS | 30979 | Values: 65.0 62.0 66.0 64.0 64.0 63.0 63.0 63.0 65.0 65.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:66.00 stddev:2.38 (3.6%) stddev-sans-first:2.49 15:40:07 INFO - PROCESS | 30979 | Values: 66.0 65.0 72.0 66.0 66.0 66.0 66.0 66.0 71.0 67.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:85.50 stddev:10.89 (12.7%) stddev-sans-first:11.54 15:40:07 INFO - PROCESS | 30979 | Values: 87.0 82.0 84.0 83.0 90.0 86.0 87.0 85.0 119.0 84.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:139.00 stddev:4.81 (3.5%) stddev-sans-first:5.07 15:40:07 INFO - PROCESS | 30979 | Values: 137.0 139.0 137.0 139.0 141.0 132.0 134.0 139.0 150.0 137.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:173.00 stddev:2.12 (1.2%) stddev-sans-first:2.06 15:40:07 INFO - PROCESS | 30979 | Values: 170.0 173.0 173.0 173.0 174.0 173.0 176.0 170.0 169.0 173.0 15:40:07 INFO - PROCESS | 30979 | 15:40:07 INFO - PROCESS | 30979 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 15:40:07 INFO - PROCESS | 30979 | Values: 68.0 68.0 69.0 70.0 69.0 67.0 68.0 68.0 67.0 66.0 15:40:07 INFO - PROCESS | 30979 | -------- Summary: end -------- 15:40:07 INFO - PROCESS | 30979 | 15:40:08 INFO - TEST-INFO | 30979: exit 0 15:40:08 INFO - TEST-OK | kraken | took 63475ms 15:40:08 INFO - SUITE-END | took 1178s 15:40:08 INFO - Completed test suite (00:19:38) 15:40:08 INFO - javascript benchmark 15:40:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6273.820556232285, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2620.4104076582767, 2669.626252324675, 2685.6642919635046, 2700.4734076855007, 2701.702812690605, 4608.147492713846, 4637.837675681352, 4656.930146047808, 4728.4798672146035, 4760.785764640591, 4598.379218805469, 4653.976730116353, 4703.106409782139, 4726.432828060629, 4731.8817029574275, 4290.592393722599, 4391.978040109795, 4404.427424434819, 4433.246348120827, 4454.643628509712, 638.2201188513301, 4201.789910504478, 4316.82732690692, 4368.344748287751, 4376.890577735566, 4320.30875059992, 4333.111712099012, 4341.4790225172865, 4364.10535840152, 4366.4105345778235, 4338.761368124752, 4357.281048626973, 4366.301391777332, 4382.496012958519, 4449.5327990560845, 4274.914501709948, 4288.378185163165, 4298.054428025829, 4339.7830108494445, 4366.847160349373, 4529.297958816389, 4605.194091034055, 4641.930371044437, 4681.297805329194, 4685.625149987993, 5071.797128114871, 5121.539061484485, 5124.718140502271, 5128.5640720538795, 5179.533841954205, 5075.796968121271, 5092.592592592584, 5113.565346945514, 5119.206522988943, 5150.072986862363, 4745.857624271277, 4751.83368582098, 4766.880827979328, 4775.092732380871, 4816.518348165156, 5053.620978426633, 5236.6334356595435, 5240.34495688039, 5256.2115682647145, 5288.524032837063, 5054.368203974504, 5054.7472626368535, 5072.9854029193575, 5097.439281679012, 5132.5124113209185, 5127.846164615067, 5134.204198349262, 5161.045206636784, 5166.922496162485, 5201.479852014844, 4878.82924097662, 5009.949900500948, 5010.148274793294, 5066.873328166752, 5100.948990510122, 4981.12830254704, 4988.55103040728, 5010.599152067826, 5019.548240658358, 5108.846734597893, 4268.317069268902, 4269.060806622538, 4272.33778764292, 4303.096349766522, 4311.849085282063, 3689.815509224528, 3696.075981004749, 3700.148965737895, 3731.589525152231, 3741.85032598693, 4337.67467439937, 4392.407025051641, 4410.16206920684, 4475.261581839025, 4492.752898590544, 5141.2545180948555, 5142.97428512855, 5145.639805213599, 5162.277281180638, 5204.089284375219, 4324.459442569679, 4433.802873224276, 4457.331400289918, 4468.039371535136, 4469.21788686985, 4442.133783912171, 4467.597916187558, 4475.664325175625, 4486.2597671383855, 4515.887102822456, 4811.78346974387, 4833.05755377698, 4833.202521583899, 4834.057358814997, 4886.169351210303, 4922.1140194765285, 4990.625703072356, 5060.468650791761, 5105.846824595268, 5128.1794912813775, 4750.976245118891, 4793.376861008186, 4813.927791083067, 4815.3258543804595, 4835.0, 5041.697498150123, 5051.393832739947, 5060.746962651926, 5069.087564238473, 5085.821996230115, 4872.780724867319, 5054.74726263678, 5072.492750724898, 5105.183170692671, 5108.361454818148, 4962.082014827269, 4975.850724478127, 4981.551660350586, 4990.850274491771, 5044.293798868088, 4775.76121193932, 4980.651354405301, 4983.950160498349, 4988.152014157529, 5027.698338099725, 4849.345338379415, 4863.319135320987, 4872.6589138761365, 4883.731394773179, 4922.581580565548, 4990.775415106256, 4996.525330093636, 5023.57299629521, 5028.1200789860895, 5077.898442031064, 4687.296905464208, 4872.634552408655, 4899.632527560376, 4902.362692849907, 4947.2579113133315], "value": 4635.148415173362, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.9152281396385, 594.2720167794453, 599.2030599302931, 603.1495591216384, 623.289450026969, 35370.2925941481, 39606.60393396065, 39753.40369894454, 39773.0, 39935.0, 39527.604723952754, 40279.19441611166, 40399.79800101003, 40646.593534064654, 40701.79649101758, 7673.232676732324, 7817.101033381148, 7836.0, 7846.646900889474, 7871.803204919895, 7618.162002179756, 7635.0, 7663.5785031823225, 7666.578338191397, 7671.884921726179, 7815.218478152196, 7822.139564647912, 7828.060632724081, 7879.0, 7886.014248218973, 7740.109887362969, 7744.070711514626, 7784.299413052824, 7801.804954876088, 7827.178146294671, 7590.8102297443, 7715.845683086307, 7735.9613201933635, 7738.729144479972, 7747.72882949094, 30097.0, 38057.858264252114, 38096.23807523834, 38208.80895595532, 38518.0, 2957.822530648168, 3056.419280336729, 3084.1364417963005, 3159.952600710968, 3171.302313491028, 28084.859575702194, 30256.8487157565, 30617.540736888965, 30776.230594235098, 31235.531467028013, 3363.4142693324075, 3369.494575813623, 3412.9488057679155, 3426.6059403168456, 3444.7071998880124, 260982.0, 267475.0, 267768.0, 268054.6597267021, 269118.0, 32856.34287314241, 58260.126098108565, 58903.87672862859, 58955.70522147362, 58982.11526827015, 33872.322553548795, 35561.64438355635, 35600.821995889855, 35682.64317356845, 35768.10579735475, 13437.059405841497, 13510.459581616626, 13544.93227533856, 13650.93174534121, 13664.248466334448, 10233.846492302622, 10267.845982310271, 10273.691789246335, 10300.948495257475, 10329.0, 8153.306968907709, 8204.83590328202, 8236.8352632947, 8266.504009759434, 8274.089850116483, 3235.8705651773666, 3238.0, 3267.8692852285644, 3273.7544684148784, 3287.320833520673, 922.6447817590276, 927.9628814847331, 945.6075728572565, 956.3257903178243, 999.2954966748425, 27359.316017099332, 28856.711432885404, 28860.41267730295, 28880.855595722307, 29240.415191696047, 996.6860438961704, 1025.8666373371414, 1037.8910214427528, 1046.7016900183412, 1064.387976913278, 15160.241987900561, 15214.239288035555, 15241.542753717406, 15479.148646824307, 15697.29362178682, 396934.0, 400914.0, 402708.0, 404614.0, 405244.0, 50325.74837125864, 50401.24398134031, 50420.49579504231, 50483.0, 50654.24018639723, 1167.0721776187952, 1199.976000480003, 1212.7210741529498, 1212.7392610588765, 1229.33001514175, 27381.86309068442, 28054.0, 28746.425071498034, 28749.856250718614, 29257.85371073131, 43600.0, 43867.341989870816, 43874.34188487239, 44036.0, 44077.77961110174, 341691.0, 344395.0, 344734.0, 345033.0, 345613.0, 26155.869220653774, 26268.474630507666, 26807.329816753958, 26823.731762682903, 26898.462030759667, 43734.78132609444, 43883.561164387946, 43992.34011489894, 44516.55483445254, 44554.0, 37252.441213382364, 37280.44079338866, 37340.25319493649, 37533.43699844559, 38352.6164738349, 37066.8146659265, 37373.4393984085, 37822.62177378191, 38066.619333807415, 38765.806170968965, 36513.634863652085, 36589.45115823318, 36898.44652330164, 37431.43852842155, 37756.05609859776, 31701.682983169874, 34105.48841767425, 34444.82777586096, 34651.0, 34762.65237347594, 25934.48131037331, 26130.216093517225, 26511.602325965512, 26563.031090672335, 26991.730082698923], "value": 16852.5671685658, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.5512162965256, 379.7873191013032, 381.8682554518691, 386.5554612195976, 394.38672863696956, 15719.449819256328, 16051.357945682174, 16967.83032169678, 16994.235259413326, 17004.4048458304, 10446.21653375998, 10529.947350263237, 10543.894561054387, 10550.47247637619, 10570.471476426186, 4512.571305725959, 4554.111948170108, 4729.196036673757, 4734.668573199878, 4756.120117778205, 4421.159979603881, 4674.813007479696, 4683.929741053887, 4697.271922851964, 4699.530046995307, 4697.553732395417, 4699.553542413465, 4706.435227772672, 4714.080754252922, 4992.600591952635, 5202.791888324463, 5327.680339179623, 5334.546563542103, 5350.732463376835, 5365.570754339644, 4967.279744437073, 5315.893682126374, 5322.308099947021, 5351.036813373591, 5370.382406023318, 11862.288262704265, 12070.517179312817, 12268.509259629693, 12290.877091229062, 12383.62849114528, 1759.5689056181318, 1867.4677716850633, 1921.4523860699774, 1958.608278344337, 1969.7833238343771, 8947.955260223722, 12924.159929604639, 13213.471461141542, 13381.397837097355, 13421.12762670433, 1662.9002259864446, 1692.2215780740873, 1703.974440383383, 1722.905240211788, 1745.5287072490482, 65018.0, 66059.33940660628, 68252.658736706, 70110.99999999949, 71220.0, 8841.646334146692, 9217.170454658979, 10111.292209545261, 10271.281010329354, 10355.948220259, 14218.431262749375, 14261.286935653383, 14323.57029289123, 14350.56948291532, 14357.64105897361, 7116.861302191624, 7174.497785154989, 7198.0, 7284.417246620258, 7385.704571817175, 6331.905021424683, 6421.678916054272, 6520.18497687789, 6676.599404035774, 6690.665466726644, 4407.779611019436, 4413.3821265022925, 4432.955670443319, 4440.555944405598, 4514.0971805639, 1869.4672018474669, 1878.877872938282, 1901.7147427885927, 1921.5964647423918, 1936.593315403781, 824.114077366833, 854.4019186569426, 880.1550511508896, 890.0387581412059, 973.9513024348754, 16582.668346633, 16811.907226030267, 17161.828381716274, 17191.0, 17369.56576085583, 854.9145085491401, 888.9111088891189, 898.1512470715112, 906.7642412972542, 920.7836158502746, 9327.393719408217, 9336.299777516577, 9634.951825240829, 9700.694682718507, 9793.118619324152, 386574.0, 415112.0, 415155.0, 415583.0, 421902.0, 47411.76294118507, 50054.21015553565, 54757.0, 86553.56723216343, 87801.56099219463, 932.9906700932904, 939.8966113727485, 955.4267439536222, 975.0347156315339, 976.5605477535138, 15789.921050394674, 15813.920930395274, 15881.761773573637, 15946.68106637884, 16101.355945762038, 32720.672793272715, 33919.0, 36593.0, 36892.446613301356, 37983.81008094942, 50059.74970125126, 50310.24534632057, 50463.0, 51201.0, 51269.48730512647, 7321.450891183289, 7416.851662966818, 7449.106107267163, 7535.623218839146, 7774.416918731231, 10856.185786065918, 11709.414529273672, 11821.82267266009, 11914.404279785802, 12011.75976480483, 9964.601415943282, 10183.473472876396, 10279.486025698834, 10297.691069267934, 10400.531976060944, 10231.48842557884, 10340.741481463023, 10400.427976461064, 10453.425061621683, 10468.26722129474, 10361.792764144826, 10545.367277963347, 10547.947260263649, 10597.205209609156, 10608.151347891995, 7432.814179645336, 7504.662290196845, 7643.159252482334, 7681.807954801175, 7738.961305193438, 8473.5339556325, 9866.013398660363, 10144.898551014396, 10254.692359229235, 10559.313644613383], "value": 7940.587534378142, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.3023953143195, 1410.8730214280715, 1519.4757808556044, 1705.1048199695151, 1721.6728821523902, 6447.516436267275, 7144.464165187612, 7160.212376638566, 7464.925350746491, 7541.208173141817, 7273.236310187433, 7414.443916706242, 7437.479376443651, 7454.366378857803, 7485.288897554738, 2174.706414634028, 2218.9889050554725, 2222.799948004679, 2223.54417344444, 2226.6548684953777, 2162.1891790578534, 2163.210428193706, 2169.490169810101, 2171.880546569938, 2204.481946742514, 2173.434906924203, 2180.3349978256624, 2191.5178660694623, 2195.1438938813876, 2236.541508990661, 2071.792820717924, 2076.771555128935, 2078.740157480315, 2079.7712251652306, 2090.362439455973, 2077.854550181488, 2081.8334533237303, 2121.7029615853794, 2134.879188426073, 2148.355493351988, 7364.889526657104, 7423.369013633794, 7439.55362678241, 7445.813854653623, 7447.2925072118605, 4303.33298338758, 4427.402300689398, 4455.732656040615, 4470.351798989161, 4471.061077173765, 2850.914472565826, 2943.632045994251, 2954.8818047278082, 2954.8818047278082, 2958.5562165675105, 874.8950125984923, 928.2573940847362, 939.4504215034214, 956.8516879883629, 958.8993155718618, 9715.36850104748, 9718.076782705632, 9762.121409073146, 9771.560279787356, 9791.804163916753, 4070.2876996526084, 4099.0, 4213.367994800805, 4232.788360581959, 4239.364095385717, 8329.5418751968, 8405.24352808238, 8466.915330846612, 8473.067962524117, 8489.108643592457, 4249.043965107888, 4326.480822301344, 4334.956650433518, 4447.510773814878, 4464.955350446519, 3528.788272703646, 3917.666998305119, 3994.6005399459823, 3995.08113133975, 4086.3053280942313, 3590.7306951978185, 3630.6006339302658, 3663.4504824276037, 3721.4603882436895, 3738.3831667775057, 1205.6563879294358, 1222.0773316146253, 1224.6264889208699, 1231.6305108467425, 1234.2594443334108, 866.2853146154448, 878.2482561276906, 886.4814083760944, 888.5113187746714, 896.8744375787395, 5033.546980771748, 5076.974615126975, 5095.159298715745, 5128.589712822965, 5142.1772516397195, 388.2487386906345, 395.58661199047066, 397.68582819572794, 413.34484841525915, 426.923153832307, 3679.7056235501095, 3712.925741485155, 3719.367707489733, 3745.7752534847996, 3751.793651349202, 8465.534395608302, 8749.47503149813, 8752.956235218911, 8755.255803256667, 8759.211670949646, 8642.956785216034, 8659.307255419551, 8684.8697269541, 8694.956525217334, 8724.912750872538, 6818.727250909908, 6832.350926661959, 6878.759243426456, 6885.827854303582, 6926.722931082701, 4953.0589188054155, 4993.550580447777, 5043.218301163325, 5158.123119069842, 5182.481751824787, 8109.472884262504, 8214.424990250864, 8256.463329883538, 8266.710665126931, 8284.668613255402, 7976.601169941596, 8227.259546640831, 8274.503529788231, 8325.666973321, 8333.499990000619, 5619.49424551776, 5893.6463812171405, 6161.784337548164, 6359.332270111571, 6431.29255781873, 7128.536645118051, 7165.140183178052, 7167.139943206848, 7208.927910720826, 7210.2789721027475, 6213.2233470816145, 6385.138006369267, 6463.709133089116, 6533.248676402274, 6565.113709649137, 6345.111684364285, 6458.709358078804, 6474.643894585843, 6495.123158373748, 6618.305077966744, 6001.339852616296, 6058.969705151622, 6073.726682299395, 6074.210352654303, 6078.179445774764, 4527.9547204527535, 4560.2019646562385, 4589.495155532822, 4676.508966558598, 4698.3657206278085, 4607.93088103672, 4627.884302892455, 4675.462321832897, 4685.465515013584, 4722.1972264714395], "value": 3933.5542148430736, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.30875713965025, 303.73878464520516, 307.28096254763864, 308.1080272610791, 308.29093085902446, 4835.613150947925, 4961.2310091935715, 4984.202527595586, 5042.848714538565, 5063.2658264551665, 4961.280614310932, 4985.202367621181, 5001.44984051754, 5005.699658020521, 5032.773525191366, 3926.27363937671, 3934.114824164554, 3940.556854750059, 3951.288768021755, 3953.308171070066, 3629.2560025194903, 3959.3269144245546, 3966.345552983769, 3972.304846651839, 3974.8012599369918, 3771.3023090728166, 3813.3707938190164, 3817.7019813263487, 3821.178446633973, 3832.310184166849, 3794.886153415388, 3807.124361396894, 3812.3519001769764, 3827.923441531154, 3857.1321452672923, 3341.195754292679, 3349.447341188701, 3350.6481819408855, 3372.460406334975, 3375.4599264117624, 4709.576138147549, 4755.690880092783, 4780.139574876521, 4845.224764037737, 4849.951500485021, 4331.371951067109, 4345.9348109778375, 4350.6736994725525, 4354.390385346054, 4362.716423432498, 5173.0, 5205.334293026233, 5212.739363031871, 5229.346331708536, 5247.556921846484, 3552.6802587766965, 3648.416253399443, 3659.6157403472516, 3660.3411385950517, 3660.762050466738, 5310.70791106489, 5482.150266708666, 5503.422140675211, 5512.283403157604, 5533.916991245094, 3656.9817150914073, 3672.908177295589, 3687.520622319081, 3690.815459227028, 3699.1491956850077, 3783.3000894834545, 3792.9569368423904, 3796.3166630006863, 3797.9240415191543, 3798.430235464647, 4226.978865105716, 4282.73659270516, 4292.635126014324, 4305.461817272841, 4346.2828633275785, 5192.792288308501, 5211.296474975906, 5226.163813789775, 5226.346706661668, 5229.424763276036, 1217.0567809947186, 1265.9177153484993, 1285.9164154330126, 1292.38611659461, 1296.9610911672664, 806.5563939833069, 811.8619834628128, 830.3938125168661, 830.9169083091642, 832.8126171611339, 4110.533679792101, 4124.845043387856, 4168.6665066794585, 4195.7692326922315, 4199.958000420018, 4772.832950846704, 4790.012144959872, 4810.811080958156, 4836.685615435056, 4837.4920633333695, 4084.4077608746566, 4132.210757738575, 4142.964258935266, 4163.439484716938, 4208.0, 2536.7823444746255, 2648.3776312566533, 2901.2166714986834, 2906.735570027045, 2909.418116376733, 5129.3075434816565, 5136.512031357015, 5136.5890728741615, 5153.639245252872, 5178.378594568676, 5354.973225133849, 5531.2809334787025, 5547.667139971614, 5553.666779993213, 5553.805616803474, 5123.231515272664, 5137.563307118862, 5143.717095559706, 5148.665336753099, 5157.355330583677, 5415.214793854948, 5451.046066938349, 5463.48096930791, 5463.617546771847, 5477.534409575231, 5081.822136225214, 5325.786968521215, 5354.919676204782, 5363.27595774581, 5364.275822763877, 5139.152039913522, 5149.407818100966, 5173.1205695031185, 5193.714345711022, 5200.297959775382, 3786.280606684721, 3809.028697682184, 3812.5424949005187, 3817.8281977311644, 3834.271488417192, 5120.513551212629, 5176.559992400537, 5441.5374693151525, 5466.234727138283, 5479.931413374354, 5141.177411614124, 5227.843164705065, 5240.34495688039, 5246.501582349671, 5271.156614941591, 5232.9738351308, 5253.868653283698, 5256.030342856274, 5285.674303306687, 5338.306020217502, 4968.180250258667, 5209.843704688866, 5219.2953951216095, 5241.344831896013, 5243.767714586993, 5306.708131052829, 5308.867278318073, 5319.9468005321, 5323.574114070942, 5359.249705041375, 5182.429932707475, 5213.921791173211, 5216.869578260574, 5245.186996015624, 5247.4490178530705], "value": 3883.962305623495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.8585068359619, 675.6317806795296, 701.4423533291033, 709.0180100560722, 728.5701436152669, 12455.564055258068, 12635.747285054293, 12776.42506087226, 12983.805242921364, 13396.0, 12679.556215532435, 12711.936440317808, 12736.80894786579, 12793.0, 12826.615201543946, 614.9354317796634, 773.787208517658, 775.6897241103547, 797.0714118052462, 809.4900212865887, 788.8422315536886, 800.6837299266787, 801.4229754576695, 801.6833350826449, 801.9719309824158, 827.6523859978794, 828.5857071464268, 829.6930135849744, 832.8292699996487, 833.8207285433631, 822.1737154160061, 823.3454403749034, 823.3536673711138, 824.1758241758242, 824.7649419915325, 606.6875559087074, 606.7330374635146, 607.5413063137303, 608.5618354784548, 610.5664977865721, 12870.16343937641, 12887.484500619965, 12887.67780805478, 12893.0, 12922.0, 12128.696782580419, 12581.811272830915, 12651.367431628474, 12785.808212876815, 12794.0, 10169.03394177552, 10247.487625618689, 10325.793484130276, 10523.631672891412, 10749.247552671317, 3272.754543409254, 3282.0646115857103, 3288.901332960015, 3311.817850018248, 3368.6462921393145, 57463.425365746225, 59561.702191489196, 60871.391286087455, 61049.38950610482, 61499.6925015372, 8596.226339629464, 8803.515806630698, 8923.687670931617, 9021.729348119567, 9177.219936305355, 19212.0, 19280.807191927903, 19503.122359493856, 19537.609247814966, 19749.703754443693, 6570.737170513222, 6582.868342633216, 6707.765228216989, 6818.863622727517, 6821.897671534931, 3604.6034936157002, 3609.187932715118, 3660.7803531787645, 3674.356987527172, 3718.6839118675216, 2891.6530016398165, 2980.344324248662, 3009.337945651953, 3103.1001009707384, 3251.674832516777, 505.4288653821159, 528.0310629993927, 539.7409243563112, 578.8321386797866, 588.9722434351997, 592.1288727620698, 610.2646311194999, 622.5424313129846, 627.6830200748593, 630.7886857902654, 8592.827736719384, 8593.226609605166, 8719.694810681722, 8737.388382813142, 9054.139856713602, 567.4637467593085, 583.2010146099785, 604.5465900574569, 606.6572386601556, 613.4693490131004, 4179.9791001045405, 4265.573442655709, 4280.743155410685, 4282.336237883133, 4315.5900189481945, 94811.52594236984, 98483.50758246164, 101745.49127254316, 103658.0, 103851.0, 53480.19779703308, 54135.0, 54328.0, 64856.35143648597, 77572.61213693896, 49141.0, 58589.0, 76436.61781691168, 76508.0, 77361.61319193368, 7996.84006319882, 8123.634436450492, 8176.46852954556, 8304.0, 8375.37184711137, 45926.54073459223, 47007.0, 47451.0, 48218.5178148214, 48897.511024889296, 66084.66957665181, 68563.0, 69051.0, 70160.0, 70721.64639176772, 9660.661876834278, 9747.707568772948, 9985.550650220903, 10062.0, 10123.848142278019, 11217.887821121685, 11258.169201549292, 11351.54593816238, 11377.943110284727, 11465.598704045318, 10641.574337026432, 10698.197635177237, 10758.462076895967, 10864.185186111232, 11265.662030138781, 10897.291676041032, 11001.559937602406, 11044.723881902695, 11110.0, 11138.665840024812, 9999.0, 10686.679399618024, 10782.622608208674, 10879.401632910265, 10938.671839844817, 8563.486190828571, 9310.115539023782, 9547.985344353021, 9884.654037108667, 9910.702678919355, 9005.234555062892, 9105.408148470327, 9116.726498205064, 9263.444193348421, 9268.907310926805], "value": 6434.303394348811, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 113.0, 116.0, 113.0, 114.0, 114.0, 114.0, 112.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 138.0, 157.0, 184.0, 132.0, 160.0, 163.0, 132.0, 191.0, 135.0], "value": 156.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 177.0, 162.0, 163.0, 163.0, 164.0, 163.0, 162.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 153.0, 76.0, 75.0, 75.0, 76.0, 161.0, 77.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 143.0, 88.0, 88.0, 88.0, 141.0, 86.0, 86.0, 131.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 119.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 147.0, 150.0, 146.0, 148.0, 146.0, 147.0, 149.0, 150.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 66.0, 64.0, 64.0, 63.0, 63.0, 63.0, 65.0, 65.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 72.0, 66.0, 66.0, 66.0, 66.0, 66.0, 71.0, 67.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 84.0, 83.0, 90.0, 86.0, 87.0, 85.0, 119.0, 84.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 137.0, 139.0, 141.0, 132.0, 134.0, 139.0, 150.0, 137.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 173.0, 173.0, 174.0, 173.0, 176.0, 170.0, 169.0, 173.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 70.0, 69.0, 67.0, 68.0, 68.0, 67.0, 66.0], "value": 68.0, "unit": "score"}]}]} 15:40:08 INFO - Return code: 0 15:40:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:40:09 ERROR - # TBPL SUCCESS # 15:40:09 INFO - Running post-action listener: _resource_record_post_action 15:40:09 INFO - ##### 15:40:09 INFO - ##### Finished run-tests step (success) 15:40:09 INFO - ##### 15:40:09 INFO - Running post-run listener: _resource_record_post_run 15:40:09 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 533024768; Read time: 0; Write time: 390904 15:40:09 INFO - TinderboxPrint: CPU usage
13.5% 15:40:09 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:40:09 INFO - TinderboxPrint: I/O write bytes / time
533,024,768 / 390,904 15:40:09 INFO - TinderboxPrint: CPU idle
8,250.7 (86.5%) 15:40:09 INFO - TinderboxPrint: CPU user
1,231.8 (12.9%) 15:40:09 INFO - TinderboxPrint: Swap in / out
0 / 0 15:40:09 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 247336960; Read time: 0; Write time: 188896 15:40:09 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 285687808; Read time: 0; Write time: 202008 15:40:09 INFO - Running post-run listener: _upload_blobber_files 15:40:09 INFO - Blob upload gear active. 15:40:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:40:09 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 15:40:09 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:40:09 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:40:10 INFO - (blobuploader) - INFO - Open directory for files ... 15:40:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:40:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:40:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:10 INFO - (blobuploader) - INFO - Done attempting. 15:40:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:40:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:40:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:14 INFO - (blobuploader) - INFO - Done attempting. 15:40:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:40:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:40:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:14 INFO - (blobuploader) - INFO - Done attempting. 15:40:14 INFO - (blobuploader) - INFO - Iteration through files over. 15:40:15 INFO - Return code: 0 15:40:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:40:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:40:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/88205156abe39a0cb32697dc16860b4abf2a2b954b406200207e5dc76572166855d662e131913d5fc137186212d99ea8daf383d31271f2f7c8f5d49e30108a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8d74481161698ba6c6a0f6177972d1ba392082df586963e8c5fdb042c8aee2cea4cce4140daa28a6e6af2f95717aace8ab6d2c04683f93640f85f8f0d02520aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/df8ff939daf65012f9728ab7660c1b44f380cb8b1a1fe180f1d9b6edf5cf90e3ebad787dc69e8fcb53abfe0b52f826a30dc16fe7255b9183fe463bf86e57d9e9"} 15:40:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:40:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:40:15 INFO - Contents: 15:40:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/88205156abe39a0cb32697dc16860b4abf2a2b954b406200207e5dc76572166855d662e131913d5fc137186212d99ea8daf383d31271f2f7c8f5d49e30108a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8d74481161698ba6c6a0f6177972d1ba392082df586963e8c5fdb042c8aee2cea4cce4140daa28a6e6af2f95717aace8ab6d2c04683f93640f85f8f0d02520aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/df8ff939daf65012f9728ab7660c1b44f380cb8b1a1fe180f1d9b6edf5cf90e3ebad787dc69e8fcb53abfe0b52f826a30dc16fe7255b9183fe463bf86e57d9e9"} 15:40:15 INFO - Running post-run listener: copy_logs_to_upload_dir 15:40:15 INFO - Copying logs to upload dir... 15:40:15 INFO - mkdir: /builds/slave/test/build/upload/logs 15:40:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.771224 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-06-03 15:40:14.089899) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 15:40:14.093290) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857 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/Larch-Non-PGO/sha512/88205156abe39a0cb32697dc16860b4abf2a2b954b406200207e5dc76572166855d662e131913d5fc137186212d99ea8daf383d31271f2f7c8f5d49e30108a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8d74481161698ba6c6a0f6177972d1ba392082df586963e8c5fdb042c8aee2cea4cce4140daa28a6e6af2f95717aace8ab6d2c04683f93640f85f8f0d02520aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/df8ff939daf65012f9728ab7660c1b44f380cb8b1a1fe180f1d9b6edf5cf90e3ebad787dc69e8fcb53abfe0b52f826a30dc16fe7255b9183fe463bf86e57d9e9"} build_url:https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011405 build_url: 'https://queue.taskcluster.net/v1/task/VMw5ps_lQ5Spvblf6P91JQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/88205156abe39a0cb32697dc16860b4abf2a2b954b406200207e5dc76572166855d662e131913d5fc137186212d99ea8daf383d31271f2f7c8f5d49e30108a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/8d74481161698ba6c6a0f6177972d1ba392082df586963e8c5fdb042c8aee2cea4cce4140daa28a6e6af2f95717aace8ab6d2c04683f93640f85f8f0d02520aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/df8ff939daf65012f9728ab7660c1b44f380cb8b1a1fe180f1d9b6edf5cf90e3ebad787dc69e8fcb53abfe0b52f826a30dc16fe7255b9183fe463bf86e57d9e9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 15:40:14.119356) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:40:14.119748) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1464992376.577379-111301857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 15:40:14.202868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 15:40:14.203220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 15:40:14.203547) ========= ========= Total master_lag: 0.16 =========