builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-014 starttime: 1466835484.5 results: success (0) buildid: 20160624203031 builduid: 5dc204d9dc1a437c8d1c2bb3dec4709b revision: 6de5b59dbcf95bb561d3c60f63743311f3f5d653 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.505095) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.505704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.506081) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.577014) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.577601) ========= 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=31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-24 23:18:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-06-24 23:18:05 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125196 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.720159) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.720613) ========= 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=31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020178 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.789909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:04.790297) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6de5b59dbcf95bb561d3c60f63743311f3f5d653 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 6de5b59dbcf95bb561d3c60f63743311f3f5d653 --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=31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-24 23:18:05,411 truncating revision to first 12 chars 2016-06-24 23:18:05,411 Setting DEBUG logging. 2016-06-24 23:18:05,411 attempt 1/10 2016-06-24 23:18:05,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6de5b59dbcf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-24 23:18:05,920 unpacking tar archive at: fx-team-6de5b59dbcf9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.840811 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:05.647408) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:05.647757) ========= script_repo_revision: 6de5b59dbcf95bb561d3c60f63743311f3f5d653 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:05.648277) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:05.648597) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-24 23:18:05.676828) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-06-24 23:18:05.677190) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:18:06 INFO - MultiFileLogger online at 20160624 23:18:06 in /builds/slave/test 23:18:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:18:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:18:06 INFO - {'append_to_log': False, 23:18:06 INFO - 'base_work_dir': '/builds/slave/test', 23:18:06 INFO - 'blob_upload_branch': 'Fx-Team', 23:18:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:18:06 INFO - 'branch': 'Fx-Team', 23:18:06 INFO - 'buildbot_json_path': 'buildprops.json', 23:18:06 INFO - 'config_files': ('talos/linux_config.py',), 23:18:06 INFO - 'default_actions': ('clobber', 23:18:06 INFO - 'read-buildbot-config', 23:18:06 INFO - 'download-and-extract', 23:18:06 INFO - 'populate-webroot', 23:18:06 INFO - 'create-virtualenv', 23:18:06 INFO - 'install', 23:18:06 INFO - 'run-tests'), 23:18:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:18:06 INFO - 'download_minidump_stackwalk': True, 23:18:06 INFO - 'download_symbols': 'ondemand', 23:18:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:18:06 INFO - 'tooltool.py': '/tools/tooltool.py', 23:18:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:18:06 INFO - '/tools/misc-python/virtualenv.py')}, 23:18:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:18:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:18:06 INFO - 'installer_path': 'installer.exe', 23:18:06 INFO - 'log_level': 'info', 23:18:06 INFO - 'log_name': 'talos', 23:18:06 INFO - 'log_to_console': True, 23:18:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:18:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:18:06 INFO - 'opt_config_files': (), 23:18:06 INFO - 'pip_index': False, 23:18:06 INFO - 'sps_profile': False, 23:18:06 INFO - 'sps_profile_interval': 0, 23:18:06 INFO - 'suite': 'dromaeojs-e10s', 23:18:06 INFO - 'system_bits': '32', 23:18:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:18:06 INFO - 'title': 'talos-linux64-ix-014', 23:18:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:18:06 INFO - 'use_talos_json': True, 23:18:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:18:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:18:06 INFO - 'work_dir': 'build'} 23:18:06 INFO - ##### 23:18:06 INFO - ##### Running clobber step. 23:18:06 INFO - ##### 23:18:06 INFO - Running pre-action listener: _resource_record_pre_action 23:18:06 INFO - Running main action method: clobber 23:18:06 INFO - rmtree: /builds/slave/test/build 23:18:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:18:06 INFO - Running post-action listener: _resource_record_post_action 23:18:06 INFO - ##### 23:18:06 INFO - ##### Finished clobber step (success) 23:18:06 INFO - ##### 23:18:06 INFO - ##### 23:18:06 INFO - ##### Running read-buildbot-config step. 23:18:06 INFO - ##### 23:18:06 INFO - Running pre-action listener: _resource_record_pre_action 23:18:06 INFO - Running main action method: read_buildbot_config 23:18:06 INFO - Using buildbot properties: 23:18:06 INFO - { 23:18:06 INFO - "project": "", 23:18:06 INFO - "product": "firefox", 23:18:06 INFO - "script_repo_revision": "production", 23:18:06 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 23:18:06 INFO - "repository": "", 23:18:06 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 23:18:06 INFO - "buildid": "20160624203031", 23:18:06 INFO - "pgo_build": "True", 23:18:06 INFO - "basedir": "/builds/slave/test", 23:18:06 INFO - "buildnumber": 570, 23:18:06 INFO - "slavename": "talos-linux64-ix-014", 23:18:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:18:06 INFO - "platform": "ubuntu64_hw", 23:18:06 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:18:06 INFO - "branch": "fx-team", 23:18:06 INFO - "repo_path": "integration/fx-team", 23:18:06 INFO - "revision": "6de5b59dbcf95bb561d3c60f63743311f3f5d653", 23:18:06 INFO - "stage_platform": "linux64-pgo", 23:18:06 INFO - "builduid": "5dc204d9dc1a437c8d1c2bb3dec4709b", 23:18:06 INFO - "slavebuilddir": "test" 23:18:06 INFO - } 23:18:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:18:06 INFO - Running post-action listener: _resource_record_post_action 23:18:06 INFO - ##### 23:18:06 INFO - ##### Finished read-buildbot-config step (success) 23:18:06 INFO - ##### 23:18:06 INFO - ##### 23:18:06 INFO - ##### Running download-and-extract step. 23:18:06 INFO - ##### 23:18:06 INFO - Running pre-action listener: _resource_record_pre_action 23:18:06 INFO - Running main action method: download_and_extract 23:18:06 INFO - mkdir: /builds/slave/test/build/tests 23:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:06 INFO - https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:18:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:18:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:18:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:18:07 INFO - Downloaded 1361 bytes. 23:18:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:18:07 INFO - Using the following test package requirements: 23:18:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:18:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:18:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:18:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'jsshell-linux-x86_64.zip'], 23:18:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:18:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:18:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:18:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:18:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:18:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:18:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:18:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:18:07 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')]} 23:18:07 INFO - https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:18:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:18:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:18:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:18:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:18:08 INFO - Downloaded 24279741 bytes. 23:18:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:18:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:18:09 INFO - Return code: 0 23:18:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:18: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')]} 23:18:09 INFO - https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:18:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:18:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:18:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:18:10 INFO - Downloaded 24279741 bytes. 23:18:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:18:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:18:12 INFO - Return code: 0 23:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:12 INFO - https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:18:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:18:13 INFO - Downloaded 14405188 bytes. 23:18:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:18:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:18:13 INFO - Return code: 0 23:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:13 INFO - https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:18:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:18:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:18:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:18:15 INFO - Downloaded 60161470 bytes. 23:18:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:18:15 INFO - mkdir: /builds/slave/test/properties 23:18:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:18:15 INFO - Writing to file /builds/slave/test/properties/build_url 23:18:15 INFO - Contents: 23:18:15 INFO - build_url:https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:18:16 INFO - Running post-action listener: _resource_record_post_action 23:18:16 INFO - Running post-action listener: set_extra_try_arguments 23:18:16 INFO - ##### 23:18:16 INFO - ##### Finished download-and-extract step (success) 23:18:16 INFO - ##### 23:18:16 INFO - ##### 23:18:16 INFO - ##### Running populate-webroot step. 23:18:16 INFO - ##### 23:18:16 INFO - Running pre-action listener: _resource_record_pre_action 23:18:16 INFO - Running main action method: populate_webroot 23:18:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:18:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:18:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:18:16 INFO - '--tppagecycles', 23:18:16 INFO - '7'], 23:18:16 INFO - 'tests': ['tsvgm']}}, 23:18:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:18:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:18:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:18:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:18:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:18:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['damp', 'tps']}, 23:18:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'tests': ['damp', 'tps']}, 23:18:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['dromaeo_dom']}, 23:18:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:18:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['basic_compositor_video']}, 23:18:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:18:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['a11yr', 23:18:16 INFO - 'ts_paint', 23:18:16 INFO - 'tpaint', 23:18:16 INFO - 'sessionrestore', 23:18:16 INFO - 'sessionrestore_no_auto_restore', 23:18:16 INFO - 'tabpaint']}, 23:18:16 INFO - 'other-e10s': {'tests': ['a11yr', 23:18:16 INFO - 'ts_paint', 23:18:16 INFO - 'tpaint', 23:18:16 INFO - 'sessionrestore', 23:18:16 INFO - 'sessionrestore_no_auto_restore', 23:18:16 INFO - 'tabpaint']}, 23:18:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:18:16 INFO - 'ts_paint', 23:18:16 INFO - 'tpaint', 23:18:16 INFO - 'sessionrestore', 23:18:16 INFO - 'sessionrestore_no_auto_restore', 23:18:16 INFO - 'tabpaint']}, 23:18:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:18:16 INFO - 'ts_paint', 23:18:16 INFO - 'tpaint', 23:18:16 INFO - 'sessionrestore', 23:18:16 INFO - 'sessionrestore_no_auto_restore', 23:18:16 INFO - 'tabpaint']}, 23:18:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['a11yr', 23:18:16 INFO - 'ts_paint', 23:18:16 INFO - 'tpaint', 23:18:16 INFO - 'sessionrestore', 23:18:16 INFO - 'sessionrestore_no_auto_restore', 23:18:16 INFO - 'tabpaint']}, 23:18:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['a11yr', 23:18:16 INFO - 'ts_paint', 23:18:16 INFO - 'tpaint', 23:18:16 INFO - 'sessionrestore', 23:18:16 INFO - 'sessionrestore_no_auto_restore', 23:18:16 INFO - 'tabpaint']}, 23:18:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['tsvgx', 23:18:16 INFO - 'tsvgr_opacity', 23:18:16 INFO - 'tart', 23:18:16 INFO - 'tscrollx', 23:18:16 INFO - 'cart']}, 23:18:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:18:16 INFO - 'tsvgr_opacity', 23:18:16 INFO - 'tart', 23:18:16 INFO - 'tscrollx', 23:18:16 INFO - 'cart']}, 23:18:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'talos_options': ['--disable-e10s'], 23:18:16 INFO - 'tests': ['tp5o']}, 23:18:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'tests': ['tp5o']}, 23:18:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'talos_options': ['--disable-e10s', 23:18:16 INFO - '--xperf_path', 23:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:16 INFO - 'tests': ['tp5n']}, 23:18:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:18:16 INFO - 'talos_options': ['--xperf_path', 23:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:18:16 INFO - 'tests': ['tp5n']}}, 23:18:16 INFO - 'talos.zip': {'path': '', 23:18:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:18:16 INFO - Running post-action listener: _resource_record_post_action 23:18:16 INFO - ##### 23:18:16 INFO - ##### Finished populate-webroot step (success) 23:18:16 INFO - ##### 23:18:16 INFO - ##### 23:18:16 INFO - ##### Running create-virtualenv step. 23:18:16 INFO - ##### 23:18:16 INFO - Running pre-action listener: _resource_record_pre_action 23:18:16 INFO - Running main action method: create_virtualenv 23:18:16 INFO - Creating virtualenv /builds/slave/test/build/venv 23:18:16 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 23:18:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:18:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:18:16 INFO - Using real prefix '/usr' 23:18:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:18:16 INFO - Installing distribute.............................................................................................................................................................................................done. 23:18:18 INFO - Installing pip.................done. 23:18:18 INFO - Return code: 0 23:18:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:18:18 INFO - Using env: {'DISPLAY': ':0', 23:18:18 INFO - 'HOME': '/home/cltbld', 23:18:18 INFO - 'LANG': 'en_US.UTF-8', 23:18:18 INFO - 'LANGUAGE': 'en_US:en', 23:18:18 INFO - 'LOGNAME': 'cltbld', 23:18:18 INFO - 'MAIL': '/var/mail/cltbld', 23:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:18 INFO - 'MOZ_NO_REMOTE': '1', 23:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:18 INFO - 'NO_EM_RESTART': '1', 23:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:18 INFO - 'PWD': '/builds/slave/test', 23:18:18 INFO - 'SHELL': '/bin/bash', 23:18:18 INFO - 'SHLVL': '1', 23:18:18 INFO - 'TERM': 'linux', 23:18:18 INFO - 'TMOUT': '86400', 23:18:18 INFO - 'USER': 'cltbld', 23:18:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:18 INFO - '_': '/tools/buildbot/bin/python'} 23:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:18:18 INFO - Cleaning up... 23:18:18 INFO - Return code: 0 23:18:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:18:18 INFO - Using env: {'DISPLAY': ':0', 23:18:18 INFO - 'HOME': '/home/cltbld', 23:18:18 INFO - 'LANG': 'en_US.UTF-8', 23:18:18 INFO - 'LANGUAGE': 'en_US:en', 23:18:18 INFO - 'LOGNAME': 'cltbld', 23:18:18 INFO - 'MAIL': '/var/mail/cltbld', 23:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:18 INFO - 'MOZ_NO_REMOTE': '1', 23:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:18 INFO - 'NO_EM_RESTART': '1', 23:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:18 INFO - 'PWD': '/builds/slave/test', 23:18:18 INFO - 'SHELL': '/bin/bash', 23:18:18 INFO - 'SHLVL': '1', 23:18:18 INFO - 'TERM': 'linux', 23:18:18 INFO - 'TMOUT': '86400', 23:18:18 INFO - 'USER': 'cltbld', 23:18:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:18 INFO - '_': '/tools/buildbot/bin/python'} 23:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:19 INFO - Downloading/unpacking psutil>=3.1.1 23:18:19 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 23:18:19 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 23:18:19 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 23:18:19 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 23:18:19 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 23:18:19 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 23:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:18:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:21 INFO - Installing collected packages: psutil 23:18:21 INFO - Running setup.py install for psutil 23:18:21 INFO - building 'psutil._psutil_linux' extension 23:18:21 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 23:18:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:18:21 INFO - building 'psutil._psutil_posix' extension 23:18:21 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 23:18:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:18:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:18:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:18:21 INFO - Successfully installed psutil 23:18:21 INFO - Cleaning up... 23:18:21 INFO - Return code: 0 23:18:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:18:21 INFO - Using env: {'DISPLAY': ':0', 23:18:21 INFO - 'HOME': '/home/cltbld', 23:18:21 INFO - 'LANG': 'en_US.UTF-8', 23:18:21 INFO - 'LANGUAGE': 'en_US:en', 23:18:21 INFO - 'LOGNAME': 'cltbld', 23:18:21 INFO - 'MAIL': '/var/mail/cltbld', 23:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:21 INFO - 'MOZ_NO_REMOTE': '1', 23:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:21 INFO - 'NO_EM_RESTART': '1', 23:18:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:21 INFO - 'PWD': '/builds/slave/test', 23:18:21 INFO - 'SHELL': '/bin/bash', 23:18:21 INFO - 'SHLVL': '1', 23:18:21 INFO - 'TERM': 'linux', 23:18:21 INFO - 'TMOUT': '86400', 23:18:21 INFO - 'USER': 'cltbld', 23:18:21 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:21 INFO - '_': '/tools/buildbot/bin/python'} 23:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:18:23 INFO - Installing collected packages: mozsystemmonitor 23:18:23 INFO - Running setup.py install for mozsystemmonitor 23:18:23 INFO - Successfully installed mozsystemmonitor 23:18:23 INFO - Cleaning up... 23:18:24 INFO - Return code: 0 23:18:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:18:24 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')]} 23:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:24 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')]} 23:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:24 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 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:24 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 23:18:24 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 23:18:24 INFO - Using env: {'DISPLAY': ':0', 23:18:24 INFO - 'HOME': '/home/cltbld', 23:18:24 INFO - 'LANG': 'en_US.UTF-8', 23:18:24 INFO - 'LANGUAGE': 'en_US:en', 23:18:24 INFO - 'LOGNAME': 'cltbld', 23:18:24 INFO - 'MAIL': '/var/mail/cltbld', 23:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:24 INFO - 'MOZ_NO_REMOTE': '1', 23:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:24 INFO - 'NO_EM_RESTART': '1', 23:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:24 INFO - 'PWD': '/builds/slave/test', 23:18:24 INFO - 'SHELL': '/bin/bash', 23:18:24 INFO - 'SHLVL': '1', 23:18:24 INFO - 'TERM': 'linux', 23:18:24 INFO - 'TMOUT': '86400', 23:18:24 INFO - 'USER': 'cltbld', 23:18:24 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:24 INFO - '_': '/tools/buildbot/bin/python'} 23:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:24 INFO - Downloading/unpacking jsonschema==2.5.1 23:18:24 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 23:18:24 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 23:18:24 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 23:18:24 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 23:18:24 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 23:18:24 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 23:18:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:18:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:26 INFO - Downloading functools32-3.2.3-2.tar.gz 23:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:18:26 INFO - warning: no files found matching '*.txt' 23:18:26 INFO - no previously-included directories found matching 'build' 23:18:26 INFO - no previously-included directories found matching 'dist' 23:18:26 INFO - no previously-included directories found matching '.git*' 23:18:26 INFO - Installing collected packages: jsonschema, functools32 23:18:26 INFO - Running setup.py install for functools32 23:18:26 INFO - warning: no files found matching '*.txt' 23:18:26 INFO - no previously-included directories found matching 'build' 23:18:26 INFO - no previously-included directories found matching 'dist' 23:18:26 INFO - no previously-included directories found matching '.git*' 23:18:26 INFO - Successfully installed jsonschema functools32 23:18:26 INFO - Cleaning up... 23:18:26 INFO - Return code: 0 23:18:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:18:26 INFO - Using env: {'DISPLAY': ':0', 23:18:26 INFO - 'HOME': '/home/cltbld', 23:18:26 INFO - 'LANG': 'en_US.UTF-8', 23:18:26 INFO - 'LANGUAGE': 'en_US:en', 23:18:26 INFO - 'LOGNAME': 'cltbld', 23:18:26 INFO - 'MAIL': '/var/mail/cltbld', 23:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:26 INFO - 'MOZ_NO_REMOTE': '1', 23:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:26 INFO - 'NO_EM_RESTART': '1', 23:18:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:26 INFO - 'PWD': '/builds/slave/test', 23:18:26 INFO - 'SHELL': '/bin/bash', 23:18:26 INFO - 'SHLVL': '1', 23:18:26 INFO - 'TERM': 'linux', 23:18:26 INFO - 'TMOUT': '86400', 23:18:26 INFO - 'USER': 'cltbld', 23:18:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:26 INFO - '_': '/tools/buildbot/bin/python'} 23:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:26 INFO - Downloading/unpacking blobuploader==1.2.4 23:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:28 INFO - Downloading blobuploader-1.2.4.tar.gz 23:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:18:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:18:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:18:29 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 23:18:29 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 23:18:29 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 23:18:29 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 23:18:29 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 23:18:29 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 23:18:29 INFO - Downloading docopt-0.6.1.tar.gz 23:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:18:29 INFO - Installing collected packages: blobuploader, requests, docopt 23:18:29 INFO - Running setup.py install for blobuploader 23:18:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:18:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:18:29 INFO - Running setup.py install for requests 23:18:30 INFO - Running setup.py install for docopt 23:18:30 INFO - Successfully installed blobuploader requests docopt 23:18:30 INFO - Cleaning up... 23:18:30 INFO - Return code: 0 23:18:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:30 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 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:30 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 23:18:30 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 23:18:30 INFO - Using env: {'DISPLAY': ':0', 23:18:30 INFO - 'HOME': '/home/cltbld', 23:18:30 INFO - 'LANG': 'en_US.UTF-8', 23:18:30 INFO - 'LANGUAGE': 'en_US:en', 23:18:30 INFO - 'LOGNAME': 'cltbld', 23:18:30 INFO - 'MAIL': '/var/mail/cltbld', 23:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:30 INFO - 'MOZ_NO_REMOTE': '1', 23:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:30 INFO - 'NO_EM_RESTART': '1', 23:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:30 INFO - 'PWD': '/builds/slave/test', 23:18:30 INFO - 'SHELL': '/bin/bash', 23:18:30 INFO - 'SHLVL': '1', 23:18:30 INFO - 'TERM': 'linux', 23:18:30 INFO - 'TMOUT': '86400', 23:18:30 INFO - 'USER': 'cltbld', 23:18:30 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:30 INFO - '_': '/tools/buildbot/bin/python'} 23:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:30 INFO - Running setup.py (path:/tmp/pip-OOEXZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:30 INFO - Running setup.py (path:/tmp/pip-wSSG3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:30 INFO - Running setup.py (path:/tmp/pip-vj3Gik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:30 INFO - Running setup.py (path:/tmp/pip-QfmA2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:30 INFO - Running setup.py (path:/tmp/pip-9koZW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:30 INFO - Running setup.py (path:/tmp/pip-arf2jU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:30 INFO - Running setup.py (path:/tmp/pip-o9hcWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:30 INFO - Running setup.py (path:/tmp/pip-5Z9xQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:30 INFO - Running setup.py (path:/tmp/pip-z6O8rE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:31 INFO - Running setup.py (path:/tmp/pip-XYdvRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:31 INFO - Running setup.py (path:/tmp/pip-RsVrtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:31 INFO - Running setup.py (path:/tmp/pip-UAeCcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:31 INFO - Running setup.py (path:/tmp/pip-9c5krn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:31 INFO - Running setup.py (path:/tmp/pip-AcAH22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:31 INFO - Running setup.py (path:/tmp/pip-nL1N03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:31 INFO - Running setup.py (path:/tmp/pip-JdeTiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:31 INFO - Running setup.py (path:/tmp/pip-QVbtLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:18:31 INFO - Running setup.py install for manifestparser 23:18:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:18:31 INFO - Running setup.py install for mozcrash 23:18:31 INFO - Running setup.py install for mozdebug 23:18:31 INFO - Running setup.py install for mozdevice 23:18:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for mozfile 23:18:32 INFO - Running setup.py install for mozhttpd 23:18:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for mozinfo 23:18:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for mozInstall 23:18:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for mozleak 23:18:32 INFO - Running setup.py install for mozlog 23:18:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for moznetwork 23:18:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for mozprocess 23:18:32 INFO - Running setup.py install for mozprofile 23:18:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:18:32 INFO - Running setup.py install for mozrunner 23:18:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:18:33 INFO - Running setup.py install for mozscreenshot 23:18:33 INFO - Running setup.py install for moztest 23:18:33 INFO - Running setup.py install for mozversion 23:18:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:18:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:18:33 INFO - Cleaning up... 23:18:33 INFO - Return code: 0 23:18:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:33 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 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:33 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 23:18:33 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 23:18:33 INFO - Using env: {'DISPLAY': ':0', 23:18:33 INFO - 'HOME': '/home/cltbld', 23:18:33 INFO - 'LANG': 'en_US.UTF-8', 23:18:33 INFO - 'LANGUAGE': 'en_US:en', 23:18:33 INFO - 'LOGNAME': 'cltbld', 23:18:33 INFO - 'MAIL': '/var/mail/cltbld', 23:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:33 INFO - 'MOZ_NO_REMOTE': '1', 23:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:33 INFO - 'NO_EM_RESTART': '1', 23:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:33 INFO - 'PWD': '/builds/slave/test', 23:18:33 INFO - 'SHELL': '/bin/bash', 23:18:33 INFO - 'SHLVL': '1', 23:18:33 INFO - 'TERM': 'linux', 23:18:33 INFO - 'TMOUT': '86400', 23:18:33 INFO - 'USER': 'cltbld', 23:18:33 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:33 INFO - '_': '/tools/buildbot/bin/python'} 23:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:18:33 INFO - Running setup.py (path:/tmp/pip-gEN2uV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:18:33 INFO - Running setup.py (path:/tmp/pip-NoTGlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:18:33 INFO - Running setup.py (path:/tmp/pip-Vz1gPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:18:33 INFO - Running setup.py (path:/tmp/pip-WFpkZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:18:33 INFO - Running setup.py (path:/tmp/pip-o_0LOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:18:33 INFO - Running setup.py (path:/tmp/pip-B95Lz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:18:34 INFO - Running setup.py (path:/tmp/pip-O8Mg9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:18:34 INFO - Running setup.py (path:/tmp/pip-3YKZF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:18:34 INFO - Running setup.py (path:/tmp/pip-VxEuud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:18:34 INFO - Running setup.py (path:/tmp/pip-hu0huF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:18:34 INFO - Running setup.py (path:/tmp/pip-rODMgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:18:34 INFO - Running setup.py (path:/tmp/pip-8vb14q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:18:34 INFO - Running setup.py (path:/tmp/pip-z2dv6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:18:34 INFO - Running setup.py (path:/tmp/pip-eNau5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:18:34 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)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:34 INFO - Running setup.py (path:/tmp/pip-AcxV1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:18:34 INFO - Running setup.py (path:/tmp/pip-zAaKLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:18:34 INFO - Running setup.py (path:/tmp/pip-8cxcLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:18:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:36 INFO - Downloading blessings-1.6.tar.gz 23:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:18:36 INFO - Installing collected packages: blessings 23:18:36 INFO - Running setup.py install for blessings 23:18:36 INFO - Successfully installed blessings 23:18:36 INFO - Cleaning up... 23:18:36 INFO - Return code: 0 23:18:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:37 INFO - Reading from file tmpfile_stdout 23:18:37 INFO - Current package versions: 23:18:37 INFO - argparse == 1.2.1 23:18:37 INFO - blessings == 1.6 23:18:37 INFO - blobuploader == 1.2.4 23:18:37 INFO - docopt == 0.6.1 23:18:37 INFO - functools32 == 3.2.3-2 23:18:37 INFO - jsonschema == 2.5.1 23:18:37 INFO - manifestparser == 1.1 23:18:37 INFO - mozInstall == 1.12 23:18:37 INFO - mozcrash == 0.17 23:18:37 INFO - mozdebug == 0.1 23:18:37 INFO - mozdevice == 0.48 23:18:37 INFO - mozfile == 1.2 23:18:37 INFO - mozhttpd == 0.7 23:18:37 INFO - mozinfo == 0.9 23:18:37 INFO - mozleak == 0.1 23:18:37 INFO - mozlog == 3.2 23:18:37 INFO - moznetwork == 0.27 23:18:37 INFO - mozprocess == 0.22 23:18:37 INFO - mozprofile == 0.28 23:18:37 INFO - mozrunner == 6.11 23:18:37 INFO - mozscreenshot == 0.1 23:18:37 INFO - mozsystemmonitor == 0.3 23:18:37 INFO - moztest == 0.7 23:18:37 INFO - mozversion == 1.4 23:18:37 INFO - psutil == 3.1.1 23:18:37 INFO - requests == 1.2.3 23:18:37 INFO - wsgiref == 0.1.2 23:18:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:37 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 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:37 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 23:18:37 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 23:18:37 INFO - Using env: {'DISPLAY': ':0', 23:18:37 INFO - 'HOME': '/home/cltbld', 23:18:37 INFO - 'LANG': 'en_US.UTF-8', 23:18:37 INFO - 'LANGUAGE': 'en_US:en', 23:18:37 INFO - 'LOGNAME': 'cltbld', 23:18:37 INFO - 'MAIL': '/var/mail/cltbld', 23:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:37 INFO - 'MOZ_NO_REMOTE': '1', 23:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:37 INFO - 'NO_EM_RESTART': '1', 23:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:37 INFO - 'PWD': '/builds/slave/test', 23:18:37 INFO - 'SHELL': '/bin/bash', 23:18:37 INFO - 'SHLVL': '1', 23:18:37 INFO - 'TERM': 'linux', 23:18:37 INFO - 'TMOUT': '86400', 23:18:37 INFO - 'USER': 'cltbld', 23:18:37 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:37 INFO - '_': '/tools/buildbot/bin/python'} 23:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 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)) 23:18:37 INFO - Cleaning up... 23:18:37 INFO - Return code: 0 23:18:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:18:37 INFO - Using env: {'DISPLAY': ':0', 23:18:37 INFO - 'HOME': '/home/cltbld', 23:18:37 INFO - 'LANG': 'en_US.UTF-8', 23:18:37 INFO - 'LANGUAGE': 'en_US:en', 23:18:37 INFO - 'LOGNAME': 'cltbld', 23:18:37 INFO - 'MAIL': '/var/mail/cltbld', 23:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:37 INFO - 'MOZ_NO_REMOTE': '1', 23:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:37 INFO - 'NO_EM_RESTART': '1', 23:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:37 INFO - 'PWD': '/builds/slave/test', 23:18:37 INFO - 'SHELL': '/bin/bash', 23:18:37 INFO - 'SHLVL': '1', 23:18:37 INFO - 'TERM': 'linux', 23:18:37 INFO - 'TMOUT': '86400', 23:18:37 INFO - 'USER': 'cltbld', 23:18:37 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:37 INFO - '_': '/tools/buildbot/bin/python'} 23:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:18:37 INFO - Cleaning up... 23:18:37 INFO - Return code: 0 23:18:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:18:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f007f017f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23e3350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, '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': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', '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 23:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:18:37 INFO - Using env: {'DISPLAY': ':0', 23:18:37 INFO - 'HOME': '/home/cltbld', 23:18:37 INFO - 'LANG': 'en_US.UTF-8', 23:18:37 INFO - 'LANGUAGE': 'en_US:en', 23:18:37 INFO - 'LOGNAME': 'cltbld', 23:18:37 INFO - 'MAIL': '/var/mail/cltbld', 23:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:37 INFO - 'MOZ_NO_REMOTE': '1', 23:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:37 INFO - 'NO_EM_RESTART': '1', 23:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:37 INFO - 'PWD': '/builds/slave/test', 23:18:37 INFO - 'SHELL': '/bin/bash', 23:18:37 INFO - 'SHLVL': '1', 23:18:37 INFO - 'TERM': 'linux', 23:18:37 INFO - 'TMOUT': '86400', 23:18:37 INFO - 'USER': 'cltbld', 23:18:37 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:37 INFO - '_': '/tools/buildbot/bin/python'} 23:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:18:37 INFO - Downloading/unpacking flake8 23:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:18:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:18:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:18:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:18:40 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 23:18:40 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 23:18:40 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 23:18:40 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 23:18:40 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 23:18:40 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 23:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:18:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:40 INFO - no previously-included directories found matching 'docs/_build' 23:18:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:18:40 INFO - Running setup.py install for pep8 23:18:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:18:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:18:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:18:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:18:40 INFO - no previously-included directories found matching 'docs/_build' 23:18:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:18:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:18:40 INFO - Cleaning up... 23:18:40 INFO - Return code: 0 23:18:40 INFO - Running post-action listener: _resource_record_post_action 23:18:40 INFO - Running post-action listener: _start_resource_monitoring 23:18:40 INFO - Starting resource monitoring. 23:18:40 INFO - ##### 23:18:40 INFO - ##### Finished create-virtualenv step (success) 23:18:40 INFO - ##### 23:18:40 INFO - ##### 23:18:40 INFO - ##### Running install step. 23:18:40 INFO - ##### 23:18:40 INFO - Running pre-action listener: _resource_record_pre_action 23:18:40 INFO - Running main action method: install 23:18:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:18:40 INFO - Reading from file tmpfile_stdout 23:18:40 INFO - Detecting whether we're running mozinstall >=1.0... 23:18:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:18:41 INFO - Reading from file tmpfile_stdout 23:18:41 INFO - Output received: 23:18:41 INFO - Usage: mozinstall [options] installer 23:18:41 INFO - Options: 23:18:41 INFO - -h, --help show this help message and exit 23:18:41 INFO - -d DEST, --destination=DEST 23:18:41 INFO - Directory to install application into. [default: 23:18:41 INFO - "/builds/slave/test"] 23:18:41 INFO - --app=APP Application being installed. [default: firefox] 23:18:41 INFO - mkdir: /builds/slave/test/build/application 23:18:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:18:58 INFO - Reading from file tmpfile_stdout 23:18:58 INFO - Output received: 23:18:58 INFO - /builds/slave/test/build/application/firefox/firefox 23:18:58 INFO - Running post-action listener: _resource_record_post_action 23:18:58 INFO - ##### 23:18:58 INFO - ##### Finished install step (success) 23:18:58 INFO - ##### 23:18:58 INFO - ##### 23:18:58 INFO - ##### Running run-tests step. 23:18:58 INFO - ##### 23:18:58 INFO - Running pre-action listener: _resource_record_pre_action 23:18:58 INFO - Running main action method: run_tests 23:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:18:58 INFO - Python 2.7.3 23:18:58 INFO - Return code: 0 23:18:58 INFO - grabbing minidump binary from tooltool 23:18:58 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')]} 23:18:58 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 0x24721c0>, '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 0x24730c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2473550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:18:58 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 23:18:58 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 23:18:58 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 23:18:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:18:58 INFO - Return code: 0 23:18:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:18:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:18:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:18:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:18:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:18:59 INFO - Return code: 0 23:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 23:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 23:18:59 INFO - Using env: {'DISPLAY': ':0', 23:18:59 INFO - 'HOME': '/home/cltbld', 23:18:59 INFO - 'LANG': 'en_US.UTF-8', 23:18:59 INFO - 'LANGUAGE': 'en_US:en', 23:18:59 INFO - 'LOGNAME': 'cltbld', 23:18:59 INFO - 'MAIL': '/var/mail/cltbld', 23:18:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:18:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:18:59 INFO - 'MOZ_NO_REMOTE': '1', 23:18:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:18:59 INFO - 'NO_EM_RESTART': '1', 23:18:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:18:59 INFO - 'PWD': '/builds/slave/test', 23:18:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:18:59 INFO - 'SHELL': '/bin/bash', 23:18:59 INFO - 'SHLVL': '1', 23:18:59 INFO - 'TERM': 'linux', 23:18:59 INFO - 'TMOUT': '86400', 23:18:59 INFO - 'USER': 'cltbld', 23:18:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070', 23:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:18:59 INFO - '_': '/tools/buildbot/bin/python'} 23:18:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 23:18:59 INFO - mozversion application_buildid: 20160624203031 23:18:59 INFO - mozversion application_changeset: 6de5b59dbcf95bb561d3c60f63743311f3f5d653 23:18:59 INFO - mozversion application_display_name: Nightly 23:18:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:18:59 INFO - mozversion application_name: Firefox 23:18:59 INFO - mozversion application_remotingname: firefox 23:18:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:18:59 INFO - mozversion application_vendor: Mozilla 23:18:59 INFO - mozversion application_version: 50.0a1 23:18:59 INFO - mozversion platform_buildid: 20160624203031 23:18:59 INFO - mozversion platform_changeset: 6de5b59dbcf95bb561d3c60f63743311f3f5d653 23:18:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:18:59 INFO - mozversion platform_version: 50.0a1 23:18:59 INFO - using testdate: 1466835539 23:18:59 INFO - actual date: 1466835539 23:18:59 INFO - starting webserver on 'localhost:47347' 23:18:59 INFO - SUITE-START | Running 2 tests 23:18:59 INFO - TEST-START | dromaeo_css 23:18:59 INFO - operating with platform_type : linux_ 23:18:59 INFO - Initialising browser for dromaeo_css test... 23:18:59 INFO - TEST-INFO | started process 10389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdnu1eN/profile http://localhost:47347/getInfo.html) 23:19:03 INFO - PROCESS | 10389 | __metrics Screen width/height:1600/1200 23:19:03 INFO - PROCESS | 10389 | colorDepth:24 23:19:03 INFO - PROCESS | 10389 | Browser inner width/height: 1024/697 23:19:03 INFO - PROCESS | 10389 | __metrics 23:19:04 ERROR - PROCESS | 10389 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 23:19:05 INFO - TEST-INFO | 10389: exit 0 23:19:05 INFO - Browser initialized. 23:19:05 INFO - Running cycle 1/1 for dromaeo_css test... 23:19:05 INFO - TEST-INFO | started process 10512 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdnu1eN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:17 INFO - PROCESS | 10512 | RSS: Main: 184721408 23:19:17 INFO - PROCESS | 10512 | 23:20:06 INFO - PROCESS | 10512 | 1466835606781 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] 23:22:14 INFO - PROCESS | 10512 | Cycle 1(1): loaded http://localhost:47347/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47347/tests/dromaeo/cssquery-ext.html) 23:22:15 INFO - PROCESS | 10512 | RSS: Main: 188293120 23:22:15 INFO - PROCESS | 10512 | 23:25:18 INFO - PROCESS | 10512 | Cycle 1(1): loaded http://localhost:47347/tests/dromaeo/cssquery-ext.html (next: http://localhost:47347/tests/dromaeo/cssquery-jquery.html) 23:25:18 INFO - PROCESS | 10512 | RSS: Main: 187691008 23:25:18 INFO - PROCESS | 10512 | 23:28:20 INFO - PROCESS | 10512 | Cycle 1(1): loaded http://localhost:47347/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47347/tests/dromaeo/cssquery-mootools.html) 23:28:21 INFO - PROCESS | 10512 | RSS: Main: 189472768 23:28:21 INFO - PROCESS | 10512 | 23:31:22 INFO - PROCESS | 10512 | Cycle 1(1): loaded http://localhost:47347/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47347/tests/dromaeo/cssquery-prototype.html) 23:31:23 INFO - PROCESS | 10512 | RSS: Main: 186597376 23:31:23 INFO - PROCESS | 10512 | 23:34:26 INFO - PROCESS | 10512 | Cycle 1(1): loaded http://localhost:47347/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47347/tests/dromaeo/cssquery-yui.html) 23:34:26 INFO - PROCESS | 10512 | RSS: Main: 188960768 23:34:26 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | Cycle 1(1): loaded http://localhost:47347/tests/dromaeo/cssquery-yui.html (next: http://localhost:47347/tests/dromaeo/cssquery-dojo.html) 23:37:29 INFO - PROCESS | 10512 | RSS: Main: 189722624 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | __start_tp_report 23:37:29 INFO - PROCESS | 10512 | _x_x_mozilla_page_load 23:37:29 INFO - PROCESS | 10512 | _x_x_mozilla_page_load_details 23:37:29 INFO - PROCESS | 10512 | |i|pagename|runs| 23:37:29 INFO - PROCESS | 10512 | |0;dojo.html;3095.9535606965915;3098.2873938994026;3107.1921300461886;3113.7508999280053;3119.532070189471;5380.461953804617;5397.190421436781;5401.567874570035;5407.594430417715;5410.053240682884;5376.704281264529;5382.6232163748555;5427.891442171164;5460.808871689482;5489.890202195954;4896.241082632188;4934.555889969902;5018.623603229754;5084;5151.4333423323405;225.16670996795705;4819.903601927959;4932.161532539477;4933.038057578756;4962.057209130272;4908.97545512272;4938.876528086791;4957.603391728653;4966.230234313687;4967.776450059756;4909.459959404463;4956.355673762424;4957.429895562021;4967.975160124212;5001.24981252815;4882.853514394574;4909.607231421478;4933.777979990873;4968.30443737877;4970.726610036447;5372.811951581715;5447.373552041527;5458.4814442628285;5461.2354270402175;5472.370677372113;5691;5855.912161317584;5872.530197584183;5873.353931067579;5910.438508341744;5824.446677565625;5867.559933004999;5877.118432235156;5910.1430292607765;5917.822465326047;5504.146857237095;5508.6970216638065;5511.283533140666;5515.310586176728;5517.917231241495;5830.76676932918;5840.328362238397;6001.789937352259;6009.2188015557695;6013.1581578579035;5659.377468478464;5697.202391665171;5699.430056994351;5703.800366987136;5752.712364381764;5760.74076666551;5766.538676905838;5773.566982476247;5781.19063331134;5785.27684039495;5526.861828454321;5731.369549349568;5745.253117094751;5754.510866576305;5755.107958266475;5784.971075144597;5789.247397838254;5815.098659707752;5849.444302791228;5889.558283128783;4334.978325108417;4353.608175264241;4366.716163449366;4391.736495810262;4459.175052615276;3813.523309586302;3828.8085595720104;3869.9226015479535;3888.475055867478;3957.92084158321;5346.4386239445075;5505;5520.1167813149705;5553.972230138824;5602.4957753802355;5881.147233651097;5905.4685078343155;5913.113033045078;5922.200502932135;5940.049592065248;5400.054990376669;5529.225908372831;5566.192902029183;5608.355039170466;5611.438856114356;5033.395992480852;5038.647294689336;5051.797928082836;5056.443791182967;5067.037262920034;6057.18228039209;6120.66336351496;6123.4488895999575;6126.707132018252;6140.815775526652;5674.631148975386;5858.677772722541;5921.3782552831335;5947.434993675594;5953.553483488669;6061.72722227492;6065.5754097212775;6076.72654730547;6104;6111.755529778759;5754.4533269339345;5755.309362876482;5763.221965034667;5763.991301522301;5766.452187042224;5652.886942261214;5657.4908258256955;5662.603617746719;5729.398413166724;5761.2222349983895;5574.581906357121;5578.86052848682;5600.971995140161;5621.241132447068;5652.63257888236;5356.384015838228;5615.831525054255;5623.409541998195;5625.774969001194;5640.971795141162;5567.916481252865;5605.271314729059;5607.046802043581;5612.7193640317;5636.661800291996;5728.684922329146;5749.482546570829;5789.797357092481;5790.420957904176;5795.39148389413;5478.589105817;5594.049011667945;5652.576056795839;5663.943360566342;5672.801451949162 23:37:29 INFO - PROCESS | 10512 | |1;ext.html;566.4646908671305;588.3439964439651;611.5749554059927;614.1923370767438;626.9968650156749;42284.92580890294;43841.12317753647;44050.7797461013;44118;49198;46058.30912536314;46411.53588464114;46450.30324545126;46498;47193.29210061852;8829.558522073887;9021.052789457111;9027.458352498837;9085.636574537011;9086.909130908705;8891.777705557348;8988.460692358447;9001.729948101567;9003.09969003101;9011.414258073237;9009.414388064788;9023.639054437816;9036;9057.365984381096;9091.04544022878;8940.016598174196;8957.283417326664;8958.462492250486;9004.054574269672;9037.322200834931;8724.127587241288;8780.165884240987;8813.647454101892;8846.24806891415;8866.86699699505;44546.55453445457;44720.3291950621;45076;45228.32157517607;45369;3615.1685112424025;3773.113318370181;3798.164403831153;3915.9804200979097;4175.498940127174;30012.8499357504;30499.39001219963;30536.8473157635;30542.236444088852;34332.82833585841;4161.958380416218;4177.475221544145;4254.744715317091;4274.615284624399;4326.069894972565;342407;343242;343607;348510;350702.2464887685;34157.658423415945;35084;60189.69905150534;60310.39689603048;60703.69648151731;36432.27135457276;36625.81687091601;36705;36726;36885.446718299245;13832.377543010589;14027.438902443992;14032.789508157386;14045.85954140466;14047;11469.942650286695;11563.537458501565;11569.595064172881;11647.6505704829;11649.53401863933;8356.916430835736;8412.327013838878;8428.4942903426;8432;8531.104234055334;3161.541576471399;3189.7767156298837;3247.4641684121857;3289.276359200972;3312.966870331266;1038.1798379280435;1076.860008198929;1139.7777433400572;1142.902813299242;1217.129752227144;33736;33746;33917.49123763145;33960.49059264113;34016.319673606886;1059.4014381874217;1102.581019212694;1104.5195340026953;1156.9305841649527;1184.1000839362148;17529.912350438168;17618.295268189384;17626.848578399487;17686.469405917844;17741.11294435273;565729;821994;837085;837750;840617;61031.38968610346;61067.6946615273;61533;61554.69222653858;61646.691766540884;1339.71196192819;1377.7726675098497;1387.9861201387857;1392.5056604905315;1395.8464568897616;32147;34380;34641.30717385688;34877.30245395027;34982.65017349896;52716.47283527115;53035.469645303056;53666;53744.731276343366;53761;491940;495534;495607;496595;496605;28358.57462138028;28399.85800071069;29042.854785725936;29233;29246.122616321543;49398.8744459394;50046.74976625239;50327;50328;51387.48612513977;41418.792906035276;42590.57409425866;42863;43031.35452968271;43880;41558;41622;41957.370639439825;42360;42738.358924616776;42108.368374473794;42902.35646465243;43057;43177.3523397143;46118;35055;37033.81483092567;38189.618103818604;38551.6144838548;38699.80650096844;28684.13947581576;28951.85524072366;29124.417511649226;29499.262518437212;29585.408291833613 23:37:29 INFO - PROCESS | 10512 | |2;jquery.html;383.83111430970354;384.76913851689005;394.76906009984157;401.8854626431467;420.53740885026474;17897.373591924268;18885.150168242428;19753.308634197805;19821.207151713916;19863.702044469344;18269.725954110698;18592.442226733187;18707.90646046771;19517.512062198453;19655.21379144836;5268.262443257947;5389.8113566025195;5452.536534394561;5475.0966090595;5483.835484935458;5743.798967036155;5871.471567558919;5895.233619629464;5914.704264786765;5946.851328716795;5784.768609255625;5860.648361098327;5930.258717660293;5933.406659334054;5961.284645842505;5794.391588883149;5841.912371314434;5855.355910849782;5866.032104702719;5879.7648094076185;5108.5402313791565;5218.165093585046;5263.605229607795;5294.788208471657;5297.4437684043005;22448.55102897949;22700.886495567418;22843.31470055901;23740.64389034166;23791.643125353134;2195.2645863635703;2399.9280021599207;2847.786416018807;3088.675689052662;3103.8292893890825;10151.086402223762;10228.622179644504;10349;10435.269531132824;10451.581936722521;1947.0264867566216;1995.930142445022;2016.3346095788354;2030.1270453704901;2039.1129858511447;80617;82941.58529207375;82966.17033829645;83269;85605;11052.171087168495;11185.552577896793;11247.94376028131;11913.70215744596;12082.33547154915;15600.92199538995;15612.3755049799;15678.451254206047;15690.058596484247;15723.135227562594;7789.922100779033;7881.369490440751;7947.334319248604;8023.478473899295;8051.275385215242;7439.776806695808;7466.589337586376;7509.249075092447;7516;7662.501937374164;4795.688280261772;4801.975990120027;4803.879903002383;4829.95170048295;4891.923776769105;1985.3349128042269;1999.67005444103;2020.8888511131736;2059.733264042633;2064.5251592133895;988.41189492252;1034.4724190662816;1036.875574930998;1044.0603456888862;1056.060106505211;19912.70130948037;20193.293234736713;20288.289909853378;20327.491812704506;20620.587588248152;982.4498280962683;985.6353149334792;989.2778271861581;993.24513369838;1011.8077565262577;10705.250632455653;11135.33188008722;11208.55165793359;11298.661040168643;11386.886131138583;456782;459252;465399.3460065424;469138;473795;89452;91817.54091229501;92472;92474.53762731144;92598;994.8905620381752;999.7050869993369;1039.3971496531992;1046.5776909505873;1060.0830281806336;17147.22837472292;17465.56336091608;17958.551036223675;18003.819961800215;18150;39927.80036099801;40457.59542404656;40561.59438405578;41481.37777933273;42313.365299519915;56651.71674141603;57322;59668.40331596629;59809.102863457956;60038.39961600503;7443.218462061622;8546.658133674584;8612.569371531523;8787.428817126865;8826.955865220632;11877.703057423634;13643.863561364657;13846.37691303914;14031.649208769863;14075.788863166856;11787.11596630239;11880.10899182582;11959.820602690792;12320.383980800745;12596.244225346509;11802.468888899848;11873.703157421134;11945.761084778429;12310.630681079581;12324.753504930031;11311.660650180507;12042.21725587834;12240.938795305967;12432.440540175894;12432.937835310766;7433.442491813027;7769.184235655175;8898.377113601986;8993.55032248372;9324.300677449082;11639.53441862316;12023;12158.331291779037;12360.196587221802;12543.811842822182 23:37:29 INFO - PROCESS | 10512 | |3;mootools.html;1481.666625009373;1520.3538496139142;1640.3848556791204;1738.5392870889216;1754.666613343464;7884.724034658788;8666.91333086669;8692.956535217316;9161.083891610844;9205.401648892826;8716.128387161281;8946.463212207273;9068.773280667987;9088.681896133636;9095.408798428114;2354.9058037678474;2402.699662542182;2404.4469771952463;2404.6873906392143;2409.662647229389;2362.303120579433;2373.679553260305;2407.5064611754547;2426.417659761662;2430.040134147011;2343.379004563792;2357.917472888449;2365.1012615206196;2373.32360277321;2390.760923907613;2301.6432452969816;2307.204014614955;2309.145616122029;2344.085806535461;2361.2325994052003;2347.7417484076736;2349.7180338359335;2381.9642705359433;2402.3633737059695;2404.8076153907646;9020.05289444612;9060.728178154666;9082.636694532212;9098.954505227497;9115.361924665276;5067.417247016567;5147.22791581262;5173.870653233699;5177.792888284464;5189.766460509249;3344.715699165574;3354.0943945134677;3408.1820363112924;3431.3995050866247;3447.6035255945635;1150.672058463334;1162.267771304073;1173.929564226149;1178.8644305904845;1211.5275042733342;12386.690332741573;12410.379481025913;12487.250764954131;12525.56160534377;12530.060245481625;4625.629949604024;4685.250359942393;4760.833370832004;4791.089692958327;4794.95205047952;10667.359958402521;10667.83998240027;10744.194185436123;10778;10835.241533092609;5207.609429292818;5242.030224408497;5261.237120617489;5268.28878101459;5290.312259406252;4191.371294305817;4587.128445595326;4706.176419126639;4853.635977301717;4884.609231261562;3916.5300163979937;3992.900177495586;4081.428599996003;4091.2022155679356;4132.504099508078;1247.8502579690496;1268.5750273658243;1276.585109839306;1285.222440423549;1289.774289499334;883.765802062454;929.762910457829;967.8161149381594;982.2044144243185;983.0579376699344;5954.672493012839;6007.48936340416;6091.299500557404;6094.786682466092;6145.446909778052;471.6839717389357;479.4103252998831;482.27658512231653;1016.9389836609827;1052.4842827014709;4316.417283666728;4360.084382279693;4372.300431930876;4397.164538737629;4413.867583972486;11155.832662510069;11398.1451391146;11403.600873969372;11411.315321080761;11452.54189832414;10991.780164396827;11251.156163287786;11277.774444511064;11319.320840749582;11364.374959377148;8328.6668533258;8434.409591328671;8468.49189048659;8478.406511544134;8487.618057187441;6402.423781859655;6643.56816806906;6663.13379260693;6734.124563806674;6752.122224111031;9564.234861211226;10510.632127875488;10574.365538067741;10612.8938710615;10619.36283822973;9984.700458986243;10178.23663225276;10200.132988696045;10205.948970255102;10252.026057524523;7268.309510596485;7432.851342973218;7692.34615057704;7954.482958607672;8006.159353267823;8739.300855931637;8778.209961103532;8786;8836.028036915806;8843.07147749477;7879.133295337394;7886.211378862068;7916;8077.232662897015;8139.112431630769;7753.263439973193;7853.842923141391;7903.209679032051;7969.681212751425;7978.800529986796;7074.575525468488;7075.292470752884;7142.321479459443;7160.785176444715;7186.820329491805;5394.541463975606;5413.133898576209;5421;5463.836084917459;5569.888602228014;5279.894402112013;5326.680399176062;5335.73321333924;5453.918191227055;5481.424450432806 23:37:29 INFO - PROCESS | 10512 | |4;prototype.html;332.080138017691;337.23447773554034;337.3186163948823;344.85171376308193;345.2060261398782;5538.612297139197;5544.722763861811;5556.416421319919;5609.35492418372;5625.156226566007;5459.044667183247;5531.557475401968;5547.389787123413;5557.693941923655;5585.69278689673;4128.855490057841;4188.413622092894;4318.69769116162;4328.567143285678;4332.393464914914;4284.207421626993;4305.870823875289;4306.440162778835;4317.481902171744;4322.416473776031;4278.9786051069705;4312.719673221246;4332.826686932519;4338.696291259613;4346.434963454746;4038.3538633818594;4256.574342565718;4262.104957958802;4278.529361770234;4316.417283666696;3745.62543745626;3765.284595926765;3771.4160052777897;3785.883164466489;3794.2411517696296;5403.83788486346;5438.564914806806;5458.808941687022;5472.7263636818;5510.724463776795;4821.54195351441;4860.538248866352;4897.2898929655;4914.606831453475;4914.754262286871;5742.564358910273;5749.425057494259;5751.511121554673;5782.537397008229;5838.357780644125;4153.1486045360625;4159.792010399498;4168.353905144708;4183.790810459495;4191.308434108369;5820.1269809528485;5934.347221805598;6012.278526576839;6054.636721796706;6062.818115456544;4223.5565265646665;4243.342281946241;4253.255680255943;4260.126674031816;4269.03946612016;4293.785310734451;4305.935410968838;4318.978405108017;4322.113966636832;4337.631301339421;4632.5830675239395;4883.438404583448;4917.901641967141;4931.334269873521;4944.752762361867;5846.8719235208955;5851.64890106595;5868.911966320508;5916.290045194604;5976.223090998143;1488.8287846897529;1493.320539154682;1498.2583621107403;1498.445575137206;1507.0354972817406;946.8863736351544;952.7141857442878;965.4014511003223;1005.1908213887745;1022.8772547294373;5596.552275817925;5625.803096891589;5646.322441307069;5647.745851436695;5657.4908258256955;5944.613600115978;5968.582199246011;5997;6004.819855404345;6024.969875150684;5409.24270602116;5540.537164272329;5699.715014249354;5713.000224960615;5727.6849773262875;3196.561547303723;3222.258880457508;3223.2908760072746;3233.544904792853;3243.41618508666;6197.96901015501;6207.6585787782105;6242.843928901723;6296.905546416808;6321.715522801485;6138.263408390932;6143;6148.600340977822;6160.199174107427;6198.752049917953;6098.969505152446;6192.225971753531;6206.441420272197;6225.750969961241;6277.802769030894;5907.3797251288;5933.495652869555;5955.5235581154275;5985.551083668655;5996.310424301087;5905.379935106752;5956.583039187389;5973.805238952314;5977.731002104829;6003.6998150093195;6006.399360063959;6182.350853160354;6195.659238741732;6198.535109866688;6274.435300822948;4195.433616461739;4212.789360532022;4217.325227963634;4240.469941257343;4249.702520823636;5771.336296325976;5863.325717542537;6039.577229593886;6039.6678182698615;6040.244969378828;5727.36998930109;5782.421757824184;5829.854253643523;5830.329512106162;5835.591508594358;5809.419058094326;5828.94171058284;5842.824715258549;5847.239858818326;5858.502027327725;5575.637583557056;5604.943950560442;5677.347105082803;5705.286839145107;5709.628874123168;5837.307180917486;5879.176915231957;5890.823275301748;5923.097682135809;5932.436418540232;5517.2896402115475;5636.5208957237455;5704.429557044263;5709.258676103662;5768.855778605568 23:37:29 INFO - PROCESS | 10512 | |5;yui.html;748.5321673953779;796.1918652567641;799.8200404908893;801.8917446144773;808.325048584432;14042.648933776662;15146.545603631896;15148.621284467894;15161.09033457992;15522.689546209069;14501.274936253198;15113;15137;15144.24278786059;15253.31360088796;793.3018943329862;811.330652211926;818.8894499242585;820.2207902492626;820.3232333325001;827.6896163938524;827.9420440569163;829.8298848736025;830.767385132164;833.5998720614109;858.1675774498726;860.9311255099577;863.5250612163285;863.7926897544576;865.8138500222451;852.7399143261302;854.7649396416035;855.1875718067798;855.5978690015683;859.1451505751754;620.9906851397233;623.9344868788758;625.1529177963839;625.5464788028688;628.6542401679104;14788.630284242872;14795.556133316019;14796.556103316918;14847.925760371238;14851.108933463918;13931.013160060056;13979.79030314546;14386.352614132284;14407.207603581799;14425.927870360685;11496.137789665809;11557.653270401901;11826.881731182664;11870.584529541424;11969.521219151224;3803.2773772983046;4024.3158663027357;4078.4697989261504;4088.2436749201493;4089.6114869087387;81350.18649813485;82025;83228.58385708033;83444.58277708634;83837.58081209555;11437.771244575228;11559.479823407966;11611.535538578362;11658.18392712502;11788.174827761974;21466.677999830015;21557.137714491244;21697.24059657936;21769.564608707737;21783.12867485283;8167.428280020342;8437.114103019218;8437.282830959437;8511.74464766058;8521.57392130391;4675.181843177498;4782.282657601388;4786.138495070851;4787.4494433140635;4893.828715994994;3714.1271801126713;3804.087019115392;3857.96142038576;3860.768353898775;3862.092408284051;643.8132941446928;643.9710213040424;653.6862306093007;664.8072059102806;666.0841343153164;635.7584118035116;722.158685131826;763.2863272839912;768.8846672999094;776.4370831147373;9914.504274786232;10196;10347.741306467247;10351.689449316533;10362.481875906326;716.4304378019488;732.7765031665286;735.4961851131992;739.8631253218065;748.6631016042694;4820.517948205152;4891.461939186686;4955.603551715926;4985.351904252424;5003.7998480061115;89756;117733.4113329428;119806;121853;121873;60578.09132863011;99566;101726;103789;105869.47065264778;90628.54685726529;91468.08531914729;91579.54210228907;92136.53931730299;92348.53825730829;9163.312751543794;9389.577469014048;9592.280578956746;9618.759531011781;9636.378967417519;52324.21513677374;52532;53149.20276195937;53556;54294.72852635712;67240;75705.62147189229;75938.24061759311;77845.61077194578;79473.60263198648;11628.53485860556;12014.279143251433;12068.215565988183;12276.202046866925;12287.447064881922;13345.866541334462;13458.93270533641;13513.864861351261;13710.52013179534;14164.079334843202;12609.369531523203;12766;12881.8067728986;12904.419301131284;13344.866551334362;12888.806667899802;13009.308789857636;13360.93319533396;13403.798943015667;13573;12257.387130643254;12726.809097863354;12728.61814145577;12788.488460461478;13179.538716144889;9678.419294842332;10238.12975897057;10243.795124097327;10251.091703056709;10287.691369258935;9579.473128977694;9706.320557560903;10306.381617102998;10413.42726150069;10416.427096509764 23:37:29 INFO - PROCESS | 10512 | __end_tp_report 23:37:29 INFO - PROCESS | 10512 | __start_cc_report 23:37:29 INFO - PROCESS | 10512 | _x_x_mozilla_cycle_collect,372 23:37:29 INFO - PROCESS | 10512 | __end_cc_report 23:37:29 INFO - PROCESS | 10512 | __startTimestamp1466836649551__endTimestamp 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | ------- Summary: start ------- 23:37:29 INFO - PROCESS | 10512 | Number of tests: 6 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | [#0] dojo.html Cycles:165 Average:5373.79 Median:5607.05 stddev:748.47 (13.3%) stddev-sans-first:729.12 23:37:29 INFO - PROCESS | 10512 | Values: 3096.0 3098.3 3107.2 3113.8 3119.5 5380.5 5397.2 5401.6 5407.6 5410.1 5376.7 5382.6 5427.9 5460.8 5489.9 4896.2 4934.6 5018.6 5084.0 5151.4 225.2 4819.9 4932.2 4933.0 4962.1 4909.0 4938.9 4957.6 4966.2 4967.8 4909.5 4956.4 4957.4 4968.0 5001.2 4882.9 4909.6 4933.8 4968.3 4970.7 5372.8 5447.4 5458.5 5461.2 5472.4 5691.0 5855.9 5872.5 5873.4 5910.4 5824.4 5867.6 5877.1 5910.1 5917.8 5504.1 5508.7 5511.3 5515.3 5517.9 5830.8 5840.3 6001.8 6009.2 6013.2 5659.4 5697.2 5699.4 5703.8 5752.7 5760.7 5766.5 5773.6 5781.2 5785.3 5526.9 5731.4 5745.3 5754.5 5755.1 5785.0 5789.2 5815.1 5849.4 5889.6 4335.0 4353.6 4366.7 4391.7 4459.2 3813.5 3828.8 3869.9 3888.5 3957.9 5346.4 5505.0 5520.1 5554.0 5602.5 5881.1 5905.5 5913.1 5922.2 5940.0 5400.1 5529.2 5566.2 5608.4 5611.4 5033.4 5038.6 5051.8 5056.4 5067.0 6057.2 6120.7 6123.4 6126.7 6140.8 5674.6 5858.7 5921.4 5947.4 5953.6 6061.7 6065.6 6076.7 6104.0 6111.8 5754.5 5755.3 5763.2 5764.0 5766.5 5652.9 5657.5 5662.6 5729.4 5761.2 5574.6 5578.9 5601.0 5621.2 5652.6 5356.4 5615.8 5623.4 5625.8 5641.0 5567.9 5605.3 5607.0 5612.7 5636.7 5728.7 5749.5 5789.8 5790.4 5795.4 5478.6 5594.0 5652.6 5663.9 5672.8 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | [#1] ext.html Cycles:180 Average:67908.56 Median:23307.37 stddev:154282.28 (661.9%) stddev-sans-first:154632.23 23:37:29 INFO - PROCESS | 10512 | Values: 566.5 588.3 611.6 614.2 627.0 42284.9 43841.1 44050.8 44118.0 49198.0 46058.3 46411.5 46450.3 46498.0 47193.3 8829.6 9021.1 9027.5 9085.6 9086.9 8891.8 8988.5 9001.7 9003.1 9011.4 9009.4 9023.6 9036.0 9057.4 9091.0 8940.0 8957.3 8958.5 9004.1 9037.3 8724.1 8780.2 8813.6 8846.2 8866.9 44546.6 44720.3 45076.0 45228.3 45369.0 3615.2 3773.1 3798.2 3916.0 4175.5 30012.8 30499.4 30536.8 30542.2 34332.8 4162.0 4177.5 4254.7 4274.6 4326.1 342407.0 343242.0 343607.0 348510.0 350702.2 34157.7 35084.0 60189.7 60310.4 60703.7 36432.3 36625.8 36705.0 36726.0 36885.4 13832.4 14027.4 14032.8 14045.9 14047.0 11469.9 11563.5 11569.6 11647.7 11649.5 8356.9 8412.3 8428.5 8432.0 8531.1 3161.5 3189.8 3247.5 3289.3 3313.0 1038.2 1076.9 1139.8 1142.9 1217.1 33736.0 33746.0 33917.5 33960.5 34016.3 1059.4 1102.6 1104.5 1156.9 1184.1 17529.9 17618.3 17626.8 17686.5 17741.1 565729.0 821994.0 837085.0 837750.0 840617.0 61031.4 61067.7 61533.0 61554.7 61646.7 1339.7 1377.8 1388.0 1392.5 1395.8 32147.0 34380.0 34641.3 34877.3 34982.7 52716.5 53035.5 53666.0 53744.7 53761.0 491940.0 495534.0 495607.0 496595.0 496605.0 28358.6 28399.9 29042.9 29233.0 29246.1 49398.9 50046.7 50327.0 50328.0 51387.5 41418.8 42590.6 42863.0 43031.4 43880.0 41558.0 41622.0 41957.4 42360.0 42738.4 42108.4 42902.4 43057.0 43177.4 46118.0 35055.0 37033.8 38189.6 38551.6 38699.8 28684.1 28951.9 29124.4 29499.3 29585.4 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | [#2] jquery.html Cycles:180 Average:28468.81 Median:2968.23 stddev:76817.70 (2588.0%) stddev-sans-first:77004.25 23:37:29 INFO - PROCESS | 10512 | Values: 383.8 384.8 394.8 401.9 420.5 17897.4 18885.2 19753.3 19821.2 19863.7 18269.7 18592.4 18707.9 19517.5 19655.2 5268.3 5389.8 5452.5 5475.1 5483.8 5743.8 5871.5 5895.2 5914.7 5946.9 5784.8 5860.6 5930.3 5933.4 5961.3 5794.4 5841.9 5855.4 5866.0 5879.8 5108.5 5218.2 5263.6 5294.8 5297.4 22448.6 22700.9 22843.3 23740.6 23791.6 2195.3 2399.9 2847.8 3088.7 3103.8 10151.1 10228.6 10349.0 10435.3 10451.6 1947.0 1995.9 2016.3 2030.1 2039.1 80617.0 82941.6 82966.2 83269.0 85605.0 11052.2 11185.6 11247.9 11913.7 12082.3 15600.9 15612.4 15678.5 15690.1 15723.1 7789.9 7881.4 7947.3 8023.5 8051.3 7439.8 7466.6 7509.2 7516.0 7662.5 4795.7 4802.0 4803.9 4830.0 4891.9 1985.3 1999.7 2020.9 2059.7 2064.5 988.4 1034.5 1036.9 1044.1 1056.1 19912.7 20193.3 20288.3 20327.5 20620.6 982.4 985.6 989.3 993.2 1011.8 10705.3 11135.3 11208.6 11298.7 11386.9 456782.0 459252.0 465399.3 469138.0 473795.0 89452.0 91817.5 92472.0 92474.5 92598.0 994.9 999.7 1039.4 1046.6 1060.1 17147.2 17465.6 17958.6 18003.8 18150.0 39927.8 40457.6 40561.6 41481.4 42313.4 56651.7 57322.0 59668.4 59809.1 60038.4 7443.2 8546.7 8612.6 8787.4 8827.0 11877.7 13643.9 13846.4 14031.6 14075.8 11787.1 11880.1 11959.8 12320.4 12596.2 11802.5 11873.7 11945.8 12310.6 12324.8 11311.7 12042.2 12240.9 12432.4 12432.9 7433.4 7769.2 8898.4 8993.6 9324.3 11639.5 12023.0 12158.3 12360.2 12543.8 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | [#3] mootools.html Cycles:180 Average:5937.44 Median:2616.26 stddev:3392.21 (129.7%) stddev-sans-first:3385.20 23:37:29 INFO - PROCESS | 10512 | Values: 1481.7 1520.4 1640.4 1738.5 1754.7 7884.7 8666.9 8693.0 9161.1 9205.4 8716.1 8946.5 9068.8 9088.7 9095.4 2354.9 2402.7 2404.4 2404.7 2409.7 2362.3 2373.7 2407.5 2426.4 2430.0 2343.4 2357.9 2365.1 2373.3 2390.8 2301.6 2307.2 2309.1 2344.1 2361.2 2347.7 2349.7 2382.0 2402.4 2404.8 9020.1 9060.7 9082.6 9099.0 9115.4 5067.4 5147.2 5173.9 5177.8 5189.8 3344.7 3354.1 3408.2 3431.4 3447.6 1150.7 1162.3 1173.9 1178.9 1211.5 12386.7 12410.4 12487.3 12525.6 12530.1 4625.6 4685.3 4760.8 4791.1 4795.0 10667.4 10667.8 10744.2 10778.0 10835.2 5207.6 5242.0 5261.2 5268.3 5290.3 4191.4 4587.1 4706.2 4853.6 4884.6 3916.5 3992.9 4081.4 4091.2 4132.5 1247.9 1268.6 1276.6 1285.2 1289.8 883.8 929.8 967.8 982.2 983.1 5954.7 6007.5 6091.3 6094.8 6145.4 471.7 479.4 482.3 1016.9 1052.5 4316.4 4360.1 4372.3 4397.2 4413.9 11155.8 11398.1 11403.6 11411.3 11452.5 10991.8 11251.2 11277.8 11319.3 11364.4 8328.7 8434.4 8468.5 8478.4 8487.6 6402.4 6643.6 6663.1 6734.1 6752.1 9564.2 10510.6 10574.4 10612.9 10619.4 9984.7 10178.2 10200.1 10205.9 10252.0 7268.3 7432.9 7692.3 7954.5 8006.2 8739.3 8778.2 8786.0 8836.0 8843.1 7879.1 7886.2 7916.0 8077.2 8139.1 7753.3 7853.8 7903.2 7969.7 7978.8 7074.6 7075.3 7142.3 7160.8 7186.8 5394.5 5413.1 5421.0 5463.8 5569.9 5279.9 5326.7 5335.7 5453.9 5481.4 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | [#4] prototype.html Cycles:180 Average:4905.29 Median:5607.15 stddev:1451.88 (25.9%) stddev-sans-first:1414.80 23:37:29 INFO - PROCESS | 10512 | Values: 332.1 337.2 337.3 344.9 345.2 5538.6 5544.7 5556.4 5609.4 5625.2 5459.0 5531.6 5547.4 5557.7 5585.7 4128.9 4188.4 4318.7 4328.6 4332.4 4284.2 4305.9 4306.4 4317.5 4322.4 4279.0 4312.7 4332.8 4338.7 4346.4 4038.4 4256.6 4262.1 4278.5 4316.4 3745.6 3765.3 3771.4 3785.9 3794.2 5403.8 5438.6 5458.8 5472.7 5510.7 4821.5 4860.5 4897.3 4914.6 4914.8 5742.6 5749.4 5751.5 5782.5 5838.4 4153.1 4159.8 4168.4 4183.8 4191.3 5820.1 5934.3 6012.3 6054.6 6062.8 4223.6 4243.3 4253.3 4260.1 4269.0 4293.8 4305.9 4319.0 4322.1 4337.6 4632.6 4883.4 4917.9 4931.3 4944.8 5846.9 5851.6 5868.9 5916.3 5976.2 1488.8 1493.3 1498.3 1498.4 1507.0 946.9 952.7 965.4 1005.2 1022.9 5596.6 5625.8 5646.3 5647.7 5657.5 5944.6 5968.6 5997.0 6004.8 6025.0 5409.2 5540.5 5699.7 5713.0 5727.7 3196.6 3222.3 3223.3 3233.5 3243.4 6198.0 6207.7 6242.8 6296.9 6321.7 6138.3 6143.0 6148.6 6160.2 6198.8 6099.0 6192.2 6206.4 6225.8 6277.8 5907.4 5933.5 5955.5 5985.6 5996.3 5905.4 5956.6 5973.8 5977.7 6003.7 6006.4 6182.4 6195.7 6198.5 6274.4 4195.4 4212.8 4217.3 4240.5 4249.7 5771.3 5863.3 6039.6 6039.7 6040.2 5727.4 5782.4 5829.9 5830.3 5835.6 5809.4 5828.9 5842.8 5847.2 5858.5 5575.6 5604.9 5677.3 5705.3 5709.6 5837.3 5879.2 5890.8 5923.1 5932.4 5517.3 5636.5 5704.4 5709.3 5768.9 23:37:29 INFO - PROCESS | 10512 | 23:37:29 INFO - PROCESS | 10512 | [#5] yui.html Cycles:180 Average:20911.14 Median:4786.79 stddev:30542.43 (638.1%) stddev-sans-first:30590.59 23:37:29 INFO - PROCESS | 10512 | Values: 748.5 796.2 799.8 801.9 808.3 14042.6 15146.5 15148.6 15161.1 15522.7 14501.3 15113.0 15137.0 15144.2 15253.3 793.3 811.3 818.9 820.2 820.3 827.7 827.9 829.8 830.8 833.6 858.2 860.9 863.5 863.8 865.8 852.7 854.8 855.2 855.6 859.1 621.0 623.9 625.2 625.5 628.7 14788.6 14795.6 14796.6 14847.9 14851.1 13931.0 13979.8 14386.4 14407.2 14425.9 11496.1 11557.7 11826.9 11870.6 11969.5 3803.3 4024.3 4078.5 4088.2 4089.6 81350.2 82025.0 83228.6 83444.6 83837.6 11437.8 11559.5 11611.5 11658.2 11788.2 21466.7 21557.1 21697.2 21769.6 21783.1 8167.4 8437.1 8437.3 8511.7 8521.6 4675.2 4782.3 4786.1 4787.4 4893.8 3714.1 3804.1 3858.0 3860.8 3862.1 643.8 644.0 653.7 664.8 666.1 635.8 722.2 763.3 768.9 776.4 9914.5 10196.0 10347.7 10351.7 10362.5 716.4 732.8 735.5 739.9 748.7 4820.5 4891.5 4955.6 4985.4 5003.8 89756.0 117733.4 119806.0 121853.0 121873.0 60578.1 99566.0 101726.0 103789.0 105869.5 90628.5 91468.1 91579.5 92136.5 92348.5 9163.3 9389.6 9592.3 9618.8 9636.4 52324.2 52532.0 53149.2 53556.0 54294.7 67240.0 75705.6 75938.2 77845.6 79473.6 11628.5 12014.3 12068.2 12276.2 12287.4 13345.9 13458.9 13513.9 13710.5 14164.1 12609.4 12766.0 12881.8 12904.4 13344.9 12888.8 13009.3 13360.9 13403.8 13573.0 12257.4 12726.8 12728.6 12788.5 13179.5 9678.4 10238.1 10243.8 10251.1 10287.7 9579.5 9706.3 10306.4 10413.4 10416.4 23:37:29 INFO - PROCESS | 10512 | -------- Summary: end -------- 23:37:29 INFO - PROCESS | 10512 | 23:37:30 INFO - TEST-INFO | 10512: exit 0 23:37:30 INFO - TEST-OK | dromaeo_css | took 1111047ms 23:37:30 INFO - TEST-START | kraken 23:37:30 INFO - operating with platform_type : linux_ 23:37:30 INFO - Initialising browser for kraken test... 23:37:30 INFO - TEST-INFO | started process 10743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp38Wpg/profile http://localhost:47347/getInfo.html) 23:37:34 INFO - PROCESS | 10743 | __metrics Screen width/height:1600/1200 23:37:34 INFO - PROCESS | 10743 | colorDepth:24 23:37:34 INFO - PROCESS | 10743 | Browser inner width/height: 1024/697 23:37:34 INFO - PROCESS | 10743 | __metrics 23:37:35 ERROR - PROCESS | 10743 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 23:37:36 INFO - TEST-INFO | 10743: exit 0 23:37:36 INFO - Browser initialized. 23:37:36 INFO - Running cycle 1/1 for kraken test... 23:37:36 INFO - TEST-INFO | started process 10866 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp38Wpg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:48 INFO - PROCESS | 10866 | RSS: Main: 185053184 23:37:48 INFO - PROCESS | 10866 | 23:37:50 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47347/tests/kraken/driver.html?testName=audio-beat-detection) 23:37:51 INFO - PROCESS | 10866 | RSS: Main: 190652416 23:37:51 INFO - PROCESS | 10866 | 23:37:54 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47347/tests/kraken/driver.html?testName=audio-dft) 23:37:54 INFO - PROCESS | 10866 | RSS: Main: 189734912 23:37:54 INFO - PROCESS | 10866 | 23:37:57 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47347/tests/kraken/driver.html?testName=audio-fft) 23:37:58 INFO - PROCESS | 10866 | RSS: Main: 184619008 23:37:58 INFO - PROCESS | 10866 | 23:38:00 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47347/tests/kraken/driver.html?testName=audio-oscillator) 23:38:01 INFO - PROCESS | 10866 | RSS: Main: 185192448 23:38:01 INFO - PROCESS | 10866 | 23:38:03 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47347/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:38:04 INFO - PROCESS | 10866 | RSS: Main: 185868288 23:38:04 INFO - PROCESS | 10866 | 23:38:07 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47347/tests/kraken/driver.html?testName=imaging-darkroom) 23:38:07 INFO - PROCESS | 10866 | RSS: Main: 185290752 23:38:07 INFO - PROCESS | 10866 | 23:38:11 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47347/tests/kraken/driver.html?testName=imaging-desaturate) 23:38:11 INFO - PROCESS | 10866 | RSS: Main: 183549952 23:38:11 INFO - PROCESS | 10866 | 23:38:14 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47347/tests/kraken/driver.html?testName=json-parse-financial) 23:38:15 INFO - PROCESS | 10866 | RSS: Main: 185384960 23:38:15 INFO - PROCESS | 10866 | 23:38:16 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47347/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:38:17 INFO - PROCESS | 10866 | RSS: Main: 185446400 23:38:17 INFO - PROCESS | 10866 | 23:38:19 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:38:19 INFO - PROCESS | 10866 | RSS: Main: 185602048 23:38:19 INFO - PROCESS | 10866 | 23:38:22 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:38:22 INFO - PROCESS | 10866 | RSS: Main: 185999360 23:38:22 INFO - PROCESS | 10866 | 23:38:25 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:38:25 INFO - PROCESS | 10866 | RSS: Main: 186470400 23:38:25 INFO - PROCESS | 10866 | 23:38:28 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:38:29 INFO - PROCESS | 10866 | RSS: Main: 186249216 23:38:29 INFO - PROCESS | 10866 | 23:38:30 INFO - PROCESS | 10866 | Cycle 1(1): loaded http://localhost:47347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47347/tests/kraken/driver.html?testName=ai-astar) 23:38:31 INFO - PROCESS | 10866 | RSS: Main: 186830848 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | __start_tp_report 23:38:31 INFO - PROCESS | 10866 | _x_x_mozilla_page_load 23:38:31 INFO - PROCESS | 10866 | _x_x_mozilla_page_load_details 23:38:31 INFO - PROCESS | 10866 | |i|pagename|runs| 23:38:31 INFO - PROCESS | 10866 | |0;ai-astar;104;113;105;112;111;113;113;110;111;111 23:38:31 INFO - PROCESS | 10866 | |1;audio-beat-detection;163;135;154;193;130;172;158;126;190;126 23:38:31 INFO - PROCESS | 10866 | |2;audio-dft;163;163;163;163;165;169;164;163;161;163 23:38:31 INFO - PROCESS | 10866 | |3;audio-fft;76;75;75;156;80;76;75;75;169;76 23:38:31 INFO - PROCESS | 10866 | |4;audio-oscillator;86;87;147;85;85;85;138;85;87;143 23:38:31 INFO - PROCESS | 10866 | |5;imaging-gaussian-blur;118;119;118;118;117;117;118;119;118;117 23:38:31 INFO - PROCESS | 10866 | |6;imaging-darkroom;147;148;147;149;148;147;149;147;148;148 23:38:31 INFO - PROCESS | 10866 | |7;imaging-desaturate;96;97;96;95;95;95;95;99;95;96 23:38:31 INFO - PROCESS | 10866 | |8;json-parse-financial;57;56;56;55;56;59;54;60;56;56 23:38:31 INFO - PROCESS | 10866 | |9;json-stringify-tinderbox;61;61;67;67;62;62;63;61;62;63 23:38:31 INFO - PROCESS | 10866 | |10;stanford-crypto-aes;78;78;79;81;81;79;80;85;140;81 23:38:31 INFO - PROCESS | 10866 | |11;stanford-crypto-ccm;132;121;124;126;120;120;123;132;127;128 23:38:31 INFO - PROCESS | 10866 | |12;stanford-crypto-pbkdf2;157;156;164;165;160;158;172;159;160;160 23:38:31 INFO - PROCESS | 10866 | |13;stanford-crypto-sha256-iterative;63;65;66;63;63;61;64;62;65;69 23:38:31 INFO - PROCESS | 10866 | __end_tp_report 23:38:31 INFO - PROCESS | 10866 | __start_cc_report 23:38:31 INFO - PROCESS | 10866 | _x_x_mozilla_cycle_collect,876 23:38:31 INFO - PROCESS | 10866 | __end_cc_report 23:38:31 INFO - PROCESS | 10866 | __startTimestamp1466836711192__endTimestamp 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | ------- Summary: start ------- 23:38:31 INFO - PROCESS | 10866 | Number of tests: 14 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.50 stddev:3.23 (2.9%) stddev-sans-first:2.50 23:38:31 INFO - PROCESS | 10866 | Values: 104.0 113.0 105.0 112.0 111.0 113.0 113.0 110.0 111.0 111.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#1] audio-beat-detection Cycles:10 Average:154.70 Median:160.50 stddev:25.25 (15.7%) stddev-sans-first:26.60 23:38:31 INFO - PROCESS | 10866 | Values: 163.0 135.0 154.0 193.0 130.0 172.0 158.0 126.0 190.0 126.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.00 stddev:2.11 (1.3%) stddev-sans-first:2.22 23:38:31 INFO - PROCESS | 10866 | Values: 163.0 163.0 163.0 163.0 165.0 169.0 164.0 163.0 161.0 163.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.50 stddev:36.63 (48.5%) stddev-sans-first:38.31 23:38:31 INFO - PROCESS | 10866 | Values: 76.0 75.0 75.0 156.0 80.0 76.0 75.0 75.0 169.0 76.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:85.00 stddev:27.60 (32.5%) stddev-sans-first:28.60 23:38:31 INFO - PROCESS | 10866 | Values: 86.0 87.0 147.0 85.0 85.0 85.0 138.0 85.0 87.0 143.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 23:38:31 INFO - PROCESS | 10866 | Values: 118.0 119.0 118.0 118.0 117.0 117.0 118.0 119.0 118.0 117.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 23:38:31 INFO - PROCESS | 10866 | Values: 147.0 148.0 147.0 149.0 148.0 147.0 149.0 147.0 148.0 148.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:1.29 (1.3%) stddev-sans-first:1.36 23:38:31 INFO - PROCESS | 10866 | Values: 96.0 97.0 96.0 95.0 95.0 95.0 95.0 99.0 95.0 96.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.00 stddev:1.78 (3.2%) stddev-sans-first:1.88 23:38:31 INFO - PROCESS | 10866 | Values: 57.0 56.0 56.0 55.0 56.0 59.0 54.0 60.0 56.0 56.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:62.50 stddev:2.28 (3.7%) stddev-sans-first:2.32 23:38:31 INFO - PROCESS | 10866 | Values: 61.0 61.0 67.0 67.0 62.0 62.0 63.0 61.0 62.0 63.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:80.50 stddev:19.01 (23.6%) stddev-sans-first:19.93 23:38:31 INFO - PROCESS | 10866 | Values: 78.0 78.0 79.0 81.0 81.0 79.0 80.0 85.0 140.0 81.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:126.50 stddev:4.50 (3.6%) stddev-sans-first:4.07 23:38:31 INFO - PROCESS | 10866 | Values: 132.0 121.0 124.0 126.0 120.0 120.0 123.0 132.0 127.0 128.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.10 Median:160.00 stddev:4.75 (3.0%) stddev-sans-first:4.80 23:38:31 INFO - PROCESS | 10866 | Values: 157.0 156.0 164.0 165.0 160.0 158.0 172.0 159.0 160.0 160.0 23:38:31 INFO - PROCESS | 10866 | 23:38:31 INFO - PROCESS | 10866 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:2.28 (3.5%) stddev-sans-first:2.39 23:38:31 INFO - PROCESS | 10866 | Values: 63.0 65.0 66.0 63.0 63.0 61.0 64.0 62.0 65.0 69.0 23:38:31 INFO - PROCESS | 10866 | -------- Summary: end -------- 23:38:31 INFO - PROCESS | 10866 | 23:38:32 INFO - TEST-INFO | 10866: exit 0 23:38:32 INFO - TEST-OK | kraken | took 61843ms 23:38:32 INFO - SUITE-END | took 1172s 23:38:32 INFO - Completed test suite (00:19:33) 23:38:32 INFO - javascript benchmark 23:38:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7314.471505292418, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.9535606965915, 3098.2873938994026, 3107.1921300461886, 3113.7508999280053, 3119.532070189471, 5380.461953804617, 5397.190421436781, 5401.567874570035, 5407.594430417715, 5410.053240682884, 5376.704281264529, 5382.6232163748555, 5427.891442171164, 5460.808871689482, 5489.890202195954, 4896.241082632188, 4934.555889969902, 5018.623603229754, 5084.0, 5151.4333423323405, 225.16670996795705, 4819.903601927959, 4932.161532539477, 4933.038057578756, 4962.057209130272, 4908.97545512272, 4938.876528086791, 4957.603391728653, 4966.230234313687, 4967.776450059756, 4909.459959404463, 4956.355673762424, 4957.429895562021, 4967.975160124212, 5001.24981252815, 4882.853514394574, 4909.607231421478, 4933.777979990873, 4968.30443737877, 4970.726610036447, 5372.811951581715, 5447.373552041527, 5458.4814442628285, 5461.2354270402175, 5472.370677372113, 5691.0, 5855.912161317584, 5872.530197584183, 5873.353931067579, 5910.438508341744, 5824.446677565625, 5867.559933004999, 5877.118432235156, 5910.1430292607765, 5917.822465326047, 5504.146857237095, 5508.6970216638065, 5511.283533140666, 5515.310586176728, 5517.917231241495, 5830.76676932918, 5840.328362238397, 6001.789937352259, 6009.2188015557695, 6013.1581578579035, 5659.377468478464, 5697.202391665171, 5699.430056994351, 5703.800366987136, 5752.712364381764, 5760.74076666551, 5766.538676905838, 5773.566982476247, 5781.19063331134, 5785.27684039495, 5526.861828454321, 5731.369549349568, 5745.253117094751, 5754.510866576305, 5755.107958266475, 5784.971075144597, 5789.247397838254, 5815.098659707752, 5849.444302791228, 5889.558283128783, 4334.978325108417, 4353.608175264241, 4366.716163449366, 4391.736495810262, 4459.175052615276, 3813.523309586302, 3828.8085595720104, 3869.9226015479535, 3888.475055867478, 3957.92084158321, 5346.4386239445075, 5505.0, 5520.1167813149705, 5553.972230138824, 5602.4957753802355, 5881.147233651097, 5905.4685078343155, 5913.113033045078, 5922.200502932135, 5940.049592065248, 5400.054990376669, 5529.225908372831, 5566.192902029183, 5608.355039170466, 5611.438856114356, 5033.395992480852, 5038.647294689336, 5051.797928082836, 5056.443791182967, 5067.037262920034, 6057.18228039209, 6120.66336351496, 6123.4488895999575, 6126.707132018252, 6140.815775526652, 5674.631148975386, 5858.677772722541, 5921.3782552831335, 5947.434993675594, 5953.553483488669, 6061.72722227492, 6065.5754097212775, 6076.72654730547, 6104.0, 6111.755529778759, 5754.4533269339345, 5755.309362876482, 5763.221965034667, 5763.991301522301, 5766.452187042224, 5652.886942261214, 5657.4908258256955, 5662.603617746719, 5729.398413166724, 5761.2222349983895, 5574.581906357121, 5578.86052848682, 5600.971995140161, 5621.241132447068, 5652.63257888236, 5356.384015838228, 5615.831525054255, 5623.409541998195, 5625.774969001194, 5640.971795141162, 5567.916481252865, 5605.271314729059, 5607.046802043581, 5612.7193640317, 5636.661800291996, 5728.684922329146, 5749.482546570829, 5789.797357092481, 5790.420957904176, 5795.39148389413, 5478.589105817, 5594.049011667945, 5652.576056795839, 5663.943360566342, 5672.801451949162], "value": 5323.7549241090765, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.4646908671305, 588.3439964439651, 611.5749554059927, 614.1923370767438, 626.9968650156749, 42284.92580890294, 43841.12317753647, 44050.7797461013, 44118.0, 49198.0, 46058.30912536314, 46411.53588464114, 46450.30324545126, 46498.0, 47193.29210061852, 8829.558522073887, 9021.052789457111, 9027.458352498837, 9085.636574537011, 9086.909130908705, 8891.777705557348, 8988.460692358447, 9001.729948101567, 9003.09969003101, 9011.414258073237, 9009.414388064788, 9023.639054437816, 9036.0, 9057.365984381096, 9091.04544022878, 8940.016598174196, 8957.283417326664, 8958.462492250486, 9004.054574269672, 9037.322200834931, 8724.127587241288, 8780.165884240987, 8813.647454101892, 8846.24806891415, 8866.86699699505, 44546.55453445457, 44720.3291950621, 45076.0, 45228.32157517607, 45369.0, 3615.1685112424025, 3773.113318370181, 3798.164403831153, 3915.9804200979097, 4175.498940127174, 30012.8499357504, 30499.39001219963, 30536.8473157635, 30542.236444088852, 34332.82833585841, 4161.958380416218, 4177.475221544145, 4254.744715317091, 4274.615284624399, 4326.069894972565, 342407.0, 343242.0, 343607.0, 348510.0, 350702.2464887685, 34157.658423415945, 35084.0, 60189.69905150534, 60310.39689603048, 60703.69648151731, 36432.27135457276, 36625.81687091601, 36705.0, 36726.0, 36885.446718299245, 13832.377543010589, 14027.438902443992, 14032.789508157386, 14045.85954140466, 14047.0, 11469.942650286695, 11563.537458501565, 11569.595064172881, 11647.6505704829, 11649.53401863933, 8356.916430835736, 8412.327013838878, 8428.4942903426, 8432.0, 8531.104234055334, 3161.541576471399, 3189.7767156298837, 3247.4641684121857, 3289.276359200972, 3312.966870331266, 1038.1798379280435, 1076.860008198929, 1139.7777433400572, 1142.902813299242, 1217.129752227144, 33736.0, 33746.0, 33917.49123763145, 33960.49059264113, 34016.319673606886, 1059.4014381874217, 1102.581019212694, 1104.5195340026953, 1156.9305841649527, 1184.1000839362148, 17529.912350438168, 17618.295268189384, 17626.848578399487, 17686.469405917844, 17741.11294435273, 565729.0, 821994.0, 837085.0, 837750.0, 840617.0, 61031.38968610346, 61067.6946615273, 61533.0, 61554.69222653858, 61646.691766540884, 1339.71196192819, 1377.7726675098497, 1387.9861201387857, 1392.5056604905315, 1395.8464568897616, 32147.0, 34380.0, 34641.30717385688, 34877.30245395027, 34982.65017349896, 52716.47283527115, 53035.469645303056, 53666.0, 53744.731276343366, 53761.0, 491940.0, 495534.0, 495607.0, 496595.0, 496605.0, 28358.57462138028, 28399.85800071069, 29042.854785725936, 29233.0, 29246.122616321543, 49398.8744459394, 50046.74976625239, 50327.0, 50328.0, 51387.48612513977, 41418.792906035276, 42590.57409425866, 42863.0, 43031.35452968271, 43880.0, 41558.0, 41622.0, 41957.370639439825, 42360.0, 42738.358924616776, 42108.368374473794, 42902.35646465243, 43057.0, 43177.3523397143, 46118.0, 35055.0, 37033.81483092567, 38189.618103818604, 38551.6144838548, 38699.80650096844, 28684.13947581576, 28951.85524072366, 29124.417511649226, 29499.262518437212, 29585.408291833613], "value": 19470.376855119237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.83111430970354, 384.76913851689005, 394.76906009984157, 401.8854626431467, 420.53740885026474, 17897.373591924268, 18885.150168242428, 19753.308634197805, 19821.207151713916, 19863.702044469344, 18269.725954110698, 18592.442226733187, 18707.90646046771, 19517.512062198453, 19655.21379144836, 5268.262443257947, 5389.8113566025195, 5452.536534394561, 5475.0966090595, 5483.835484935458, 5743.798967036155, 5871.471567558919, 5895.233619629464, 5914.704264786765, 5946.851328716795, 5784.768609255625, 5860.648361098327, 5930.258717660293, 5933.406659334054, 5961.284645842505, 5794.391588883149, 5841.912371314434, 5855.355910849782, 5866.032104702719, 5879.7648094076185, 5108.5402313791565, 5218.165093585046, 5263.605229607795, 5294.788208471657, 5297.4437684043005, 22448.55102897949, 22700.886495567418, 22843.31470055901, 23740.64389034166, 23791.643125353134, 2195.2645863635703, 2399.9280021599207, 2847.786416018807, 3088.675689052662, 3103.8292893890825, 10151.086402223762, 10228.622179644504, 10349.0, 10435.269531132824, 10451.581936722521, 1947.0264867566216, 1995.930142445022, 2016.3346095788354, 2030.1270453704901, 2039.1129858511447, 80617.0, 82941.58529207375, 82966.17033829645, 83269.0, 85605.0, 11052.171087168495, 11185.552577896793, 11247.94376028131, 11913.70215744596, 12082.33547154915, 15600.92199538995, 15612.3755049799, 15678.451254206047, 15690.058596484247, 15723.135227562594, 7789.922100779033, 7881.369490440751, 7947.334319248604, 8023.478473899295, 8051.275385215242, 7439.776806695808, 7466.589337586376, 7509.249075092447, 7516.0, 7662.501937374164, 4795.688280261772, 4801.975990120027, 4803.879903002383, 4829.95170048295, 4891.923776769105, 1985.3349128042269, 1999.67005444103, 2020.8888511131736, 2059.733264042633, 2064.5251592133895, 988.41189492252, 1034.4724190662816, 1036.875574930998, 1044.0603456888862, 1056.060106505211, 19912.70130948037, 20193.293234736713, 20288.289909853378, 20327.491812704506, 20620.587588248152, 982.4498280962683, 985.6353149334792, 989.2778271861581, 993.24513369838, 1011.8077565262577, 10705.250632455653, 11135.33188008722, 11208.55165793359, 11298.661040168643, 11386.886131138583, 456782.0, 459252.0, 465399.3460065424, 469138.0, 473795.0, 89452.0, 91817.54091229501, 92472.0, 92474.53762731144, 92598.0, 994.8905620381752, 999.7050869993369, 1039.3971496531992, 1046.5776909505873, 1060.0830281806336, 17147.22837472292, 17465.56336091608, 17958.551036223675, 18003.819961800215, 18150.0, 39927.80036099801, 40457.59542404656, 40561.59438405578, 41481.37777933273, 42313.365299519915, 56651.71674141603, 57322.0, 59668.40331596629, 59809.102863457956, 60038.39961600503, 7443.218462061622, 8546.658133674584, 8612.569371531523, 8787.428817126865, 8826.955865220632, 11877.703057423634, 13643.863561364657, 13846.37691303914, 14031.649208769863, 14075.788863166856, 11787.11596630239, 11880.10899182582, 11959.820602690792, 12320.383980800745, 12596.244225346509, 11802.468888899848, 11873.703157421134, 11945.761084778429, 12310.630681079581, 12324.753504930031, 11311.660650180507, 12042.21725587834, 12240.938795305967, 12432.440540175894, 12432.937835310766, 7433.442491813027, 7769.184235655175, 8898.377113601986, 8993.55032248372, 9324.300677449082, 11639.53441862316, 12023.0, 12158.331291779037, 12360.196587221802, 12543.811842822182], "value": 9339.243813297197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.666625009373, 1520.3538496139142, 1640.3848556791204, 1738.5392870889216, 1754.666613343464, 7884.724034658788, 8666.91333086669, 8692.956535217316, 9161.083891610844, 9205.401648892826, 8716.128387161281, 8946.463212207273, 9068.773280667987, 9088.681896133636, 9095.408798428114, 2354.9058037678474, 2402.699662542182, 2404.4469771952463, 2404.6873906392143, 2409.662647229389, 2362.303120579433, 2373.679553260305, 2407.5064611754547, 2426.417659761662, 2430.040134147011, 2343.379004563792, 2357.917472888449, 2365.1012615206196, 2373.32360277321, 2390.760923907613, 2301.6432452969816, 2307.204014614955, 2309.145616122029, 2344.085806535461, 2361.2325994052003, 2347.7417484076736, 2349.7180338359335, 2381.9642705359433, 2402.3633737059695, 2404.8076153907646, 9020.05289444612, 9060.728178154666, 9082.636694532212, 9098.954505227497, 9115.361924665276, 5067.417247016567, 5147.22791581262, 5173.870653233699, 5177.792888284464, 5189.766460509249, 3344.715699165574, 3354.0943945134677, 3408.1820363112924, 3431.3995050866247, 3447.6035255945635, 1150.672058463334, 1162.267771304073, 1173.929564226149, 1178.8644305904845, 1211.5275042733342, 12386.690332741573, 12410.379481025913, 12487.250764954131, 12525.56160534377, 12530.060245481625, 4625.629949604024, 4685.250359942393, 4760.833370832004, 4791.089692958327, 4794.95205047952, 10667.359958402521, 10667.83998240027, 10744.194185436123, 10778.0, 10835.241533092609, 5207.609429292818, 5242.030224408497, 5261.237120617489, 5268.28878101459, 5290.312259406252, 4191.371294305817, 4587.128445595326, 4706.176419126639, 4853.635977301717, 4884.609231261562, 3916.5300163979937, 3992.900177495586, 4081.428599996003, 4091.2022155679356, 4132.504099508078, 1247.8502579690496, 1268.5750273658243, 1276.585109839306, 1285.222440423549, 1289.774289499334, 883.765802062454, 929.762910457829, 967.8161149381594, 982.2044144243185, 983.0579376699344, 5954.672493012839, 6007.48936340416, 6091.299500557404, 6094.786682466092, 6145.446909778052, 471.6839717389357, 479.4103252998831, 482.27658512231653, 1016.9389836609827, 1052.4842827014709, 4316.417283666728, 4360.084382279693, 4372.300431930876, 4397.164538737629, 4413.867583972486, 11155.832662510069, 11398.1451391146, 11403.600873969372, 11411.315321080761, 11452.54189832414, 10991.780164396827, 11251.156163287786, 11277.774444511064, 11319.320840749582, 11364.374959377148, 8328.6668533258, 8434.409591328671, 8468.49189048659, 8478.406511544134, 8487.618057187441, 6402.423781859655, 6643.56816806906, 6663.13379260693, 6734.124563806674, 6752.122224111031, 9564.234861211226, 10510.632127875488, 10574.365538067741, 10612.8938710615, 10619.36283822973, 9984.700458986243, 10178.23663225276, 10200.132988696045, 10205.948970255102, 10252.026057524523, 7268.309510596485, 7432.851342973218, 7692.34615057704, 7954.482958607672, 8006.159353267823, 8739.300855931637, 8778.209961103532, 8786.0, 8836.028036915806, 8843.07147749477, 7879.133295337394, 7886.211378862068, 7916.0, 8077.232662897015, 8139.112431630769, 7753.263439973193, 7853.842923141391, 7903.209679032051, 7969.681212751425, 7978.800529986796, 7074.575525468488, 7075.292470752884, 7142.321479459443, 7160.785176444715, 7186.820329491805, 5394.541463975606, 5413.133898576209, 5421.0, 5463.836084917459, 5569.888602228014, 5279.894402112013, 5326.680399176062, 5335.73321333924, 5453.918191227055, 5481.424450432806], "value": 4709.459410474065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.080138017691, 337.23447773554034, 337.3186163948823, 344.85171376308193, 345.2060261398782, 5538.612297139197, 5544.722763861811, 5556.416421319919, 5609.35492418372, 5625.156226566007, 5459.044667183247, 5531.557475401968, 5547.389787123413, 5557.693941923655, 5585.69278689673, 4128.855490057841, 4188.413622092894, 4318.69769116162, 4328.567143285678, 4332.393464914914, 4284.207421626993, 4305.870823875289, 4306.440162778835, 4317.481902171744, 4322.416473776031, 4278.9786051069705, 4312.719673221246, 4332.826686932519, 4338.696291259613, 4346.434963454746, 4038.3538633818594, 4256.574342565718, 4262.104957958802, 4278.529361770234, 4316.417283666696, 3745.62543745626, 3765.284595926765, 3771.4160052777897, 3785.883164466489, 3794.2411517696296, 5403.83788486346, 5438.564914806806, 5458.808941687022, 5472.7263636818, 5510.724463776795, 4821.54195351441, 4860.538248866352, 4897.2898929655, 4914.606831453475, 4914.754262286871, 5742.564358910273, 5749.425057494259, 5751.511121554673, 5782.537397008229, 5838.357780644125, 4153.1486045360625, 4159.792010399498, 4168.353905144708, 4183.790810459495, 4191.308434108369, 5820.1269809528485, 5934.347221805598, 6012.278526576839, 6054.636721796706, 6062.818115456544, 4223.5565265646665, 4243.342281946241, 4253.255680255943, 4260.126674031816, 4269.03946612016, 4293.785310734451, 4305.935410968838, 4318.978405108017, 4322.113966636832, 4337.631301339421, 4632.5830675239395, 4883.438404583448, 4917.901641967141, 4931.334269873521, 4944.752762361867, 5846.8719235208955, 5851.64890106595, 5868.911966320508, 5916.290045194604, 5976.223090998143, 1488.8287846897529, 1493.320539154682, 1498.2583621107403, 1498.445575137206, 1507.0354972817406, 946.8863736351544, 952.7141857442878, 965.4014511003223, 1005.1908213887745, 1022.8772547294373, 5596.552275817925, 5625.803096891589, 5646.322441307069, 5647.745851436695, 5657.4908258256955, 5944.613600115978, 5968.582199246011, 5997.0, 6004.819855404345, 6024.969875150684, 5409.24270602116, 5540.537164272329, 5699.715014249354, 5713.000224960615, 5727.6849773262875, 3196.561547303723, 3222.258880457508, 3223.2908760072746, 3233.544904792853, 3243.41618508666, 6197.96901015501, 6207.6585787782105, 6242.843928901723, 6296.905546416808, 6321.715522801485, 6138.263408390932, 6143.0, 6148.600340977822, 6160.199174107427, 6198.752049917953, 6098.969505152446, 6192.225971753531, 6206.441420272197, 6225.750969961241, 6277.802769030894, 5907.3797251288, 5933.495652869555, 5955.5235581154275, 5985.551083668655, 5996.310424301087, 5905.379935106752, 5956.583039187389, 5973.805238952314, 5977.731002104829, 6003.6998150093195, 6006.399360063959, 6182.350853160354, 6195.659238741732, 6198.535109866688, 6274.435300822948, 4195.433616461739, 4212.789360532022, 4217.325227963634, 4240.469941257343, 4249.702520823636, 5771.336296325976, 5863.325717542537, 6039.577229593886, 6039.6678182698615, 6040.244969378828, 5727.36998930109, 5782.421757824184, 5829.854253643523, 5830.329512106162, 5835.591508594358, 5809.419058094326, 5828.94171058284, 5842.824715258549, 5847.239858818326, 5858.502027327725, 5575.637583557056, 5604.943950560442, 5677.347105082803, 5705.286839145107, 5709.628874123168, 5837.307180917486, 5879.176915231957, 5890.823275301748, 5923.097682135809, 5932.436418540232, 5517.2896402115475, 5636.5208957237455, 5704.429557044263, 5709.258676103662, 5768.855778605568], "value": 4439.650998227535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.5321673953779, 796.1918652567641, 799.8200404908893, 801.8917446144773, 808.325048584432, 14042.648933776662, 15146.545603631896, 15148.621284467894, 15161.09033457992, 15522.689546209069, 14501.274936253198, 15113.0, 15137.0, 15144.24278786059, 15253.31360088796, 793.3018943329862, 811.330652211926, 818.8894499242585, 820.2207902492626, 820.3232333325001, 827.6896163938524, 827.9420440569163, 829.8298848736025, 830.767385132164, 833.5998720614109, 858.1675774498726, 860.9311255099577, 863.5250612163285, 863.7926897544576, 865.8138500222451, 852.7399143261302, 854.7649396416035, 855.1875718067798, 855.5978690015683, 859.1451505751754, 620.9906851397233, 623.9344868788758, 625.1529177963839, 625.5464788028688, 628.6542401679104, 14788.630284242872, 14795.556133316019, 14796.556103316918, 14847.925760371238, 14851.108933463918, 13931.013160060056, 13979.79030314546, 14386.352614132284, 14407.207603581799, 14425.927870360685, 11496.137789665809, 11557.653270401901, 11826.881731182664, 11870.584529541424, 11969.521219151224, 3803.2773772983046, 4024.3158663027357, 4078.4697989261504, 4088.2436749201493, 4089.6114869087387, 81350.18649813485, 82025.0, 83228.58385708033, 83444.58277708634, 83837.58081209555, 11437.771244575228, 11559.479823407966, 11611.535538578362, 11658.18392712502, 11788.174827761974, 21466.677999830015, 21557.137714491244, 21697.24059657936, 21769.564608707737, 21783.12867485283, 8167.428280020342, 8437.114103019218, 8437.282830959437, 8511.74464766058, 8521.57392130391, 4675.181843177498, 4782.282657601388, 4786.138495070851, 4787.4494433140635, 4893.828715994994, 3714.1271801126713, 3804.087019115392, 3857.96142038576, 3860.768353898775, 3862.092408284051, 643.8132941446928, 643.9710213040424, 653.6862306093007, 664.8072059102806, 666.0841343153164, 635.7584118035116, 722.158685131826, 763.2863272839912, 768.8846672999094, 776.4370831147373, 9914.504274786232, 10196.0, 10347.741306467247, 10351.689449316533, 10362.481875906326, 716.4304378019488, 732.7765031665286, 735.4961851131992, 739.8631253218065, 748.6631016042694, 4820.517948205152, 4891.461939186686, 4955.603551715926, 4985.351904252424, 5003.7998480061115, 89756.0, 117733.4113329428, 119806.0, 121853.0, 121873.0, 60578.09132863011, 99566.0, 101726.0, 103789.0, 105869.47065264778, 90628.54685726529, 91468.08531914729, 91579.54210228907, 92136.53931730299, 92348.53825730829, 9163.312751543794, 9389.577469014048, 9592.280578956746, 9618.759531011781, 9636.378967417519, 52324.21513677374, 52532.0, 53149.20276195937, 53556.0, 54294.72852635712, 67240.0, 75705.62147189229, 75938.24061759311, 77845.61077194578, 79473.60263198648, 11628.53485860556, 12014.279143251433, 12068.215565988183, 12276.202046866925, 12287.447064881922, 13345.866541334462, 13458.93270533641, 13513.864861351261, 13710.52013179534, 14164.079334843202, 12609.369531523203, 12766.0, 12881.8067728986, 12904.419301131284, 13344.866551334362, 12888.806667899802, 13009.308789857636, 13360.93319533396, 13403.798943015667, 13573.0, 12257.387130643254, 12726.809097863354, 12728.61814145577, 12788.488460461478, 13179.538716144889, 9678.419294842332, 10238.12975897057, 10243.795124097327, 10251.091703056709, 10287.691369258935, 9579.473128977694, 9706.320557560903, 10306.381617102998, 10413.42726150069, 10416.427096509764], "value": 7565.404079603115, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 105.0, 112.0, 111.0, 113.0, 113.0, 110.0, 111.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 135.0, 154.0, 193.0, 130.0, 172.0, 158.0, 126.0, 190.0, 126.0], "value": 154.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 163.0, 165.0, 169.0, 164.0, 163.0, 161.0, 163.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 156.0, 80.0, 76.0, 75.0, 75.0, 169.0, 76.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 147.0, 85.0, 85.0, 85.0, 138.0, 85.0, 87.0, 143.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 118.0, 118.0, 117.0, 117.0, 118.0, 119.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 149.0, 148.0, 147.0, 149.0, 147.0, 148.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 95.0, 95.0, 95.0, 95.0, 99.0, 95.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 55.0, 56.0, 59.0, 54.0, 60.0, 56.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 67.0, 67.0, 62.0, 62.0, 63.0, 61.0, 62.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 81.0, 81.0, 79.0, 80.0, 85.0, 140.0, 81.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 121.0, 124.0, 126.0, 120.0, 120.0, 123.0, 132.0, 127.0, 128.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 164.0, 165.0, 160.0, 158.0, 172.0, 159.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 66.0, 63.0, 63.0, 61.0, 64.0, 62.0, 65.0, 69.0], "value": 64.1, "unit": "score"}]}]} 23:38:32 INFO - Return code: 0 23:38:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:38:32 ERROR - # TBPL SUCCESS # 23:38:32 INFO - Running post-action listener: _resource_record_post_action 23:38:32 INFO - ##### 23:38:32 INFO - ##### Finished run-tests step (success) 23:38:32 INFO - ##### 23:38:32 INFO - Running post-run listener: _resource_record_post_run 23:38:32 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 560078848; Read time: 0; Write time: 505068 23:38:32 INFO - TinderboxPrint: CPU usage
13.5% 23:38:32 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 23:38:32 INFO - TinderboxPrint: I/O write bytes / time
560,078,848 / 505,068 23:38:32 INFO - TinderboxPrint: CPU idle
8,218.6 (86.5%) 23:38:32 INFO - TinderboxPrint: CPU user
1,225.8 (12.9%) 23:38:32 INFO - TinderboxPrint: Swap in / out
0 / 0 23:38:32 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 267210752; Read time: 0; Write time: 272928 23:38:32 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 292868096; Read time: 0; Write time: 232140 23:38:33 INFO - Running post-run listener: _upload_blobber_files 23:38:33 INFO - Blob upload gear active. 23:38:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:38:33 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 23:38:33 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:38:33 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:38:33 INFO - (blobuploader) - INFO - Open directory for files ... 23:38:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:38:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:38:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:36 INFO - (blobuploader) - INFO - Done attempting. 23:38:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:38:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:38:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:37 INFO - (blobuploader) - INFO - Done attempting. 23:38:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:38:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:38:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:38:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:38:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:38:38 INFO - (blobuploader) - INFO - Done attempting. 23:38:38 INFO - (blobuploader) - INFO - Iteration through files over. 23:38:38 INFO - Return code: 0 23:38:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:38:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:38:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58ade91f830b8db7df5426471001c3a5ed06f64218ab7bba576de30fd53b509f8562134aa37bcbca482ba0b7226bbbc7df0dfbfd63d7537a625cf4e98e42d592", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc503dbedf7206948b9b02ea456b69323808e2f4e2ebfe1cd166284e7cf8d74f8693723a70a992258e2ebeef99ace7d4796e547e4c859c6f560d4944eb85744a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea1b2e14109a1561cfc27ed2a738fc500f2b44bf02518e98065521f3939cd3b39d130cf2506d885486afeb4d1461082c1218c643813e984f79f6529fc4f6a3d4"} 23:38:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:38:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:38:38 INFO - Contents: 23:38:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58ade91f830b8db7df5426471001c3a5ed06f64218ab7bba576de30fd53b509f8562134aa37bcbca482ba0b7226bbbc7df0dfbfd63d7537a625cf4e98e42d592", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc503dbedf7206948b9b02ea456b69323808e2f4e2ebfe1cd166284e7cf8d74f8693723a70a992258e2ebeef99ace7d4796e547e4c859c6f560d4944eb85744a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea1b2e14109a1561cfc27ed2a738fc500f2b44bf02518e98065521f3939cd3b39d130cf2506d885486afeb4d1461082c1218c643813e984f79f6529fc4f6a3d4"} 23:38:38 INFO - Running post-run listener: copy_logs_to_upload_dir 23:38:38 INFO - Copying logs to upload dir... 23:38:38 INFO - mkdir: /builds/slave/test/build/upload/logs 23:38:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.359237 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-06-24 23:38:38.065764) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-24 23:38:38.069726) ========= 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=31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070 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/Fx-Team/sha512/58ade91f830b8db7df5426471001c3a5ed06f64218ab7bba576de30fd53b509f8562134aa37bcbca482ba0b7226bbbc7df0dfbfd63d7537a625cf4e98e42d592", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc503dbedf7206948b9b02ea456b69323808e2f4e2ebfe1cd166284e7cf8d74f8693723a70a992258e2ebeef99ace7d4796e547e4c859c6f560d4944eb85744a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea1b2e14109a1561cfc27ed2a738fc500f2b44bf02518e98065521f3939cd3b39d130cf2506d885486afeb4d1461082c1218c643813e984f79f6529fc4f6a3d4"} build_url:https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011088 build_url: 'https://queue.taskcluster.net/v1/task/bHnSa4QQSX2ppGsMmRnvzg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/58ade91f830b8db7df5426471001c3a5ed06f64218ab7bba576de30fd53b509f8562134aa37bcbca482ba0b7226bbbc7df0dfbfd63d7537a625cf4e98e42d592", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc503dbedf7206948b9b02ea456b69323808e2f4e2ebfe1cd166284e7cf8d74f8693723a70a992258e2ebeef99ace7d4796e547e4c859c6f560d4944eb85744a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea1b2e14109a1561cfc27ed2a738fc500f2b44bf02518e98065521f3939cd3b39d130cf2506d885486afeb4d1461082c1218c643813e984f79f6529fc4f6a3d4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-24 23:38:38.096087) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:38:38.096516) ========= 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=31acecf42b6a79ec49471d4a000001bb-1466832644.446263-165718070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-24 23:38:38.181499) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-24 23:38:38.182275) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-24 23:38:38.182765) ========= ========= Total master_lag: 0.21 =========