builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-020 starttime: 1464975444.45 results: success (0) buildid: 20160603092112 builduid: 12f3316431674c3aba798b33bea9ed2b revision: f73e91c7bd1fe252c32004934f960b5bf27204cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.449642) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.450526) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.451090) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.467987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.468284) ========= 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=af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 10:37:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-06-03 10:37:24 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.600993) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.601554) ========= 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=af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019025 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.651587) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:24.652017) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev f73e91c7bd1fe252c32004934f960b5bf27204cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev f73e91c7bd1fe252c32004934f960b5bf27204cf --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=af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 10:37:24,960 truncating revision to first 12 chars 2016-06-03 10:37:24,960 Setting DEBUG logging. 2016-06-03 10:37:24,960 attempt 1/10 2016-06-03 10:37:24,960 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/f73e91c7bd1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 10:37:25,271 unpacking tar archive at: larch-f73e91c7bd1f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.670544 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:25.345540) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:25.346208) ========= script_repo_revision: f73e91c7bd1fe252c32004934f960b5bf27204cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:25.347096) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:25.347737) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 10:37:25.359151) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-06-03 10:37:25.359780) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:37:25 INFO - MultiFileLogger online at 20160603 10:37:25 in /builds/slave/test 10:37:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 10:37:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:37:25 INFO - {'append_to_log': False, 10:37:25 INFO - 'base_work_dir': '/builds/slave/test', 10:37:25 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 10:37:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:37:25 INFO - 'branch': 'Larch-Non-PGO', 10:37:25 INFO - 'buildbot_json_path': 'buildprops.json', 10:37:25 INFO - 'config_files': ('talos/linux_config.py',), 10:37:25 INFO - 'default_actions': ('clobber', 10:37:25 INFO - 'read-buildbot-config', 10:37:25 INFO - 'download-and-extract', 10:37:25 INFO - 'populate-webroot', 10:37:25 INFO - 'create-virtualenv', 10:37:25 INFO - 'install', 10:37:25 INFO - 'run-tests'), 10:37:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:37:25 INFO - 'download_minidump_stackwalk': True, 10:37:25 INFO - 'download_symbols': 'ondemand', 10:37:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:37:25 INFO - 'tooltool.py': '/tools/tooltool.py', 10:37:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:37:25 INFO - '/tools/misc-python/virtualenv.py')}, 10:37:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:37:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:37:25 INFO - 'installer_path': 'installer.exe', 10:37:25 INFO - 'log_level': 'info', 10:37:25 INFO - 'log_name': 'talos', 10:37:25 INFO - 'log_to_console': True, 10:37:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:37:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:37:25 INFO - 'opt_config_files': (), 10:37:25 INFO - 'pip_index': False, 10:37:25 INFO - 'sps_profile': False, 10:37:25 INFO - 'sps_profile_interval': 0, 10:37:25 INFO - 'suite': 'dromaeojs', 10:37:25 INFO - 'system_bits': '32', 10:37:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:37:25 INFO - 'title': 'talos-linux64-ix-020', 10:37:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:37:25 INFO - 'use_talos_json': True, 10:37:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:37:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:37:25 INFO - 'work_dir': 'build'} 10:37:25 INFO - ##### 10:37:25 INFO - ##### Running clobber step. 10:37:25 INFO - ##### 10:37:25 INFO - Running pre-action listener: _resource_record_pre_action 10:37:25 INFO - Running main action method: clobber 10:37:25 INFO - rmtree: /builds/slave/test/build 10:37:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:37:26 INFO - Running post-action listener: _resource_record_post_action 10:37:26 INFO - ##### 10:37:26 INFO - ##### Finished clobber step (success) 10:37:26 INFO - ##### 10:37:26 INFO - ##### 10:37:26 INFO - ##### Running read-buildbot-config step. 10:37:26 INFO - ##### 10:37:26 INFO - Running pre-action listener: _resource_record_pre_action 10:37:26 INFO - Running main action method: read_buildbot_config 10:37:26 INFO - Using buildbot properties: 10:37:26 INFO - { 10:37:26 INFO - "project": "", 10:37:26 INFO - "product": "firefox", 10:37:26 INFO - "script_repo_revision": "production", 10:37:26 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 10:37:26 INFO - "repository": "", 10:37:26 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 10:37:26 INFO - "buildid": "20160603092112", 10:37:26 INFO - "pgo_build": "False", 10:37:26 INFO - "basedir": "/builds/slave/test", 10:37:26 INFO - "buildnumber": 5, 10:37:26 INFO - "slavename": "talos-linux64-ix-020", 10:37:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:37:26 INFO - "platform": "ubuntu64_hw", 10:37:26 INFO - "scheduler": "tests-larch-linux64-talos", 10:37:26 INFO - "branch": "larch", 10:37:26 INFO - "repo_path": "projects/larch", 10:37:26 INFO - "revision": "f73e91c7bd1fe252c32004934f960b5bf27204cf", 10:37:26 INFO - "stage_platform": "linux64", 10:37:26 INFO - "builduid": "12f3316431674c3aba798b33bea9ed2b", 10:37:26 INFO - "slavebuilddir": "test" 10:37:26 INFO - } 10:37:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:37:26 INFO - Running post-action listener: _resource_record_post_action 10:37:26 INFO - ##### 10:37:26 INFO - ##### Finished read-buildbot-config step (success) 10:37:26 INFO - ##### 10:37:26 INFO - ##### 10:37:26 INFO - ##### Running download-and-extract step. 10:37:26 INFO - ##### 10:37:26 INFO - Running pre-action listener: _resource_record_pre_action 10:37:26 INFO - Running main action method: download_and_extract 10:37:26 INFO - mkdir: /builds/slave/test/build/tests 10:37: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')]} 10:37:26 INFO - https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:37:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:37:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:37:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:37:27 INFO - Downloaded 1361 bytes. 10:37:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:37:27 INFO - Using the following test package requirements: 10:37:27 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:37:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:37:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:37:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'jsshell-linux-x86_64.zip'], 10:37:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:37:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:37:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:37:27 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:37:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:37:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:37:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:37:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:37:27 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')]} 10:37:27 INFO - https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:37:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:37:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:37:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:37:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:37:33 INFO - Downloaded 22869115 bytes. 10:37:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:37:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:37:34 INFO - Return code: 0 10:37:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:37:34 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')]} 10:37:34 INFO - https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:37:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:37:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:37:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:37:38 INFO - Downloaded 22869115 bytes. 10:37:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:37:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:37:39 INFO - Return code: 0 10:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:37:39 INFO - https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:37:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:37:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:37:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:37:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:37:40 INFO - Downloaded 10833377 bytes. 10:37:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:37:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 10:37:41 INFO - Return code: 0 10:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:37:41 INFO - https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:37:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:37:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:37:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:37:43 INFO - Downloaded 55238516 bytes. 10:37:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:37:43 INFO - mkdir: /builds/slave/test/properties 10:37:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:37:43 INFO - Writing to file /builds/slave/test/properties/build_url 10:37:43 INFO - Contents: 10:37:43 INFO - build_url:https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:37:44 INFO - Running post-action listener: _resource_record_post_action 10:37:44 INFO - Running post-action listener: set_extra_try_arguments 10:37:44 INFO - ##### 10:37:44 INFO - ##### Finished download-and-extract step (success) 10:37:44 INFO - ##### 10:37:44 INFO - ##### 10:37:44 INFO - ##### Running populate-webroot step. 10:37:44 INFO - ##### 10:37:44 INFO - Running pre-action listener: _resource_record_pre_action 10:37:44 INFO - Running main action method: populate_webroot 10:37:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:37:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:37:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:37:44 INFO - '--tppagecycles', 10:37:44 INFO - '7'], 10:37:44 INFO - 'tests': ['tsvgm']}}, 10:37:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:37:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:37:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:37:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:37:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:37:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:37:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['damp', 'tps']}, 10:37:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'tests': ['damp', 'tps']}, 10:37:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['dromaeo_dom']}, 10:37:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:37:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['a11yr', 10:37:44 INFO - 'ts_paint', 10:37:44 INFO - 'tpaint', 10:37:44 INFO - 'sessionrestore', 10:37:44 INFO - 'sessionrestore_no_auto_restore', 10:37:44 INFO - 'tabpaint']}, 10:37:44 INFO - 'other-e10s': {'tests': ['a11yr', 10:37:44 INFO - 'ts_paint', 10:37:44 INFO - 'tpaint', 10:37:44 INFO - 'sessionrestore', 10:37:44 INFO - 'sessionrestore_no_auto_restore', 10:37:44 INFO - 'tabpaint']}, 10:37:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:37:44 INFO - 'ts_paint', 10:37:44 INFO - 'tpaint', 10:37:44 INFO - 'sessionrestore', 10:37:44 INFO - 'sessionrestore_no_auto_restore', 10:37:44 INFO - 'tabpaint']}, 10:37:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:37:44 INFO - 'ts_paint', 10:37:44 INFO - 'tpaint', 10:37:44 INFO - 'sessionrestore', 10:37:44 INFO - 'sessionrestore_no_auto_restore', 10:37:44 INFO - 'tabpaint']}, 10:37:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['a11yr', 10:37:44 INFO - 'ts_paint', 10:37:44 INFO - 'tpaint', 10:37:44 INFO - 'sessionrestore', 10:37:44 INFO - 'sessionrestore_no_auto_restore', 10:37:44 INFO - 'tabpaint']}, 10:37:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['a11yr', 10:37:44 INFO - 'ts_paint', 10:37:44 INFO - 'tpaint', 10:37:44 INFO - 'sessionrestore', 10:37:44 INFO - 'sessionrestore_no_auto_restore', 10:37:44 INFO - 'tabpaint']}, 10:37:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['tsvgx', 10:37:44 INFO - 'tsvgr_opacity', 10:37:44 INFO - 'tart', 10:37:44 INFO - 'tscrollx', 10:37:44 INFO - 'cart']}, 10:37:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:37:44 INFO - 'tsvgr_opacity', 10:37:44 INFO - 'tart', 10:37:44 INFO - 'tscrollx', 10:37:44 INFO - 'cart']}, 10:37:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'talos_options': ['--disable-e10s'], 10:37:44 INFO - 'tests': ['tp5o']}, 10:37:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'tests': ['tp5o']}, 10:37:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'talos_options': ['--disable-e10s', 10:37:44 INFO - '--xperf_path', 10:37:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:37:44 INFO - 'tests': ['tp5n']}, 10:37:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:44 INFO - 'talos_options': ['--xperf_path', 10:37:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:37:44 INFO - 'tests': ['tp5n']}}, 10:37:44 INFO - 'talos.zip': {'path': '', 10:37:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:37:44 INFO - Running post-action listener: _resource_record_post_action 10:37:44 INFO - ##### 10:37:44 INFO - ##### Finished populate-webroot step (success) 10:37:44 INFO - ##### 10:37:44 INFO - ##### 10:37:44 INFO - ##### Running create-virtualenv step. 10:37:44 INFO - ##### 10:37:44 INFO - Running pre-action listener: _resource_record_pre_action 10:37:44 INFO - Running main action method: create_virtualenv 10:37:44 INFO - Creating virtualenv /builds/slave/test/build/venv 10:37:44 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 10:37:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:37:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:37:44 INFO - Using real prefix '/usr' 10:37:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:37:45 INFO - Installing distribute.............................................................................................................................................................................................done. 10:37:47 INFO - Installing pip.................done. 10:37:47 INFO - Return code: 0 10:37:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:37:47 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')]} 10:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:47 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')]} 10:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:47 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:37:47 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 10:37:47 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 10:37:47 INFO - Using env: {'DISPLAY': ':0', 10:37:47 INFO - 'HOME': '/home/cltbld', 10:37:47 INFO - 'LANG': 'en_US.UTF-8', 10:37:47 INFO - 'LANGUAGE': 'en_US:en', 10:37:47 INFO - 'LOGNAME': 'cltbld', 10:37:47 INFO - 'MAIL': '/var/mail/cltbld', 10:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:47 INFO - 'MOZ_NO_REMOTE': '1', 10:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:47 INFO - 'NO_EM_RESTART': '1', 10:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:47 INFO - 'PWD': '/builds/slave/test', 10:37:47 INFO - 'SHELL': '/bin/bash', 10:37:47 INFO - 'SHLVL': '1', 10:37:47 INFO - 'TERM': 'linux', 10:37:47 INFO - 'TMOUT': '86400', 10:37:47 INFO - 'USER': 'cltbld', 10:37:47 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:47 INFO - '_': '/tools/buildbot/bin/python'} 10:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:47 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 10:37:47 INFO - Cleaning up... 10:37:47 INFO - Return code: 0 10:37:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:37:47 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')]} 10:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:47 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')]} 10:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:47 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:37:47 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 10:37:47 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 10:37:47 INFO - Using env: {'DISPLAY': ':0', 10:37:47 INFO - 'HOME': '/home/cltbld', 10:37:47 INFO - 'LANG': 'en_US.UTF-8', 10:37:47 INFO - 'LANGUAGE': 'en_US:en', 10:37:47 INFO - 'LOGNAME': 'cltbld', 10:37:47 INFO - 'MAIL': '/var/mail/cltbld', 10:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:47 INFO - 'MOZ_NO_REMOTE': '1', 10:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:47 INFO - 'NO_EM_RESTART': '1', 10:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:47 INFO - 'PWD': '/builds/slave/test', 10:37:47 INFO - 'SHELL': '/bin/bash', 10:37:47 INFO - 'SHLVL': '1', 10:37:47 INFO - 'TERM': 'linux', 10:37:47 INFO - 'TMOUT': '86400', 10:37:47 INFO - 'USER': 'cltbld', 10:37:47 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:47 INFO - '_': '/tools/buildbot/bin/python'} 10:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:47 INFO - Downloading/unpacking psutil>=3.1.1 10:37:47 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 10:37:47 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 10:37:47 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 10:37:47 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 10:37:47 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 10:37:47 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 10:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:37:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:37:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:37:49 INFO - Installing collected packages: psutil 10:37:49 INFO - Running setup.py install for psutil 10:37:49 INFO - building 'psutil._psutil_linux' extension 10:37:49 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 10:37:49 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 10:37:49 INFO - building 'psutil._psutil_posix' extension 10:37:49 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 10:37:50 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 10:37:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:37:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:37:50 INFO - Successfully installed psutil 10:37:50 INFO - Cleaning up... 10:37:50 INFO - Return code: 0 10:37:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:37:50 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')]} 10:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:50 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')]} 10:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:50 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:37:50 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 10:37:50 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 10:37:50 INFO - Using env: {'DISPLAY': ':0', 10:37:50 INFO - 'HOME': '/home/cltbld', 10:37:50 INFO - 'LANG': 'en_US.UTF-8', 10:37:50 INFO - 'LANGUAGE': 'en_US:en', 10:37:50 INFO - 'LOGNAME': 'cltbld', 10:37:50 INFO - 'MAIL': '/var/mail/cltbld', 10:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:50 INFO - 'MOZ_NO_REMOTE': '1', 10:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:50 INFO - 'NO_EM_RESTART': '1', 10:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:50 INFO - 'PWD': '/builds/slave/test', 10:37:50 INFO - 'SHELL': '/bin/bash', 10:37:50 INFO - 'SHLVL': '1', 10:37:50 INFO - 'TERM': 'linux', 10:37:50 INFO - 'TMOUT': '86400', 10:37:50 INFO - 'USER': 'cltbld', 10:37:50 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:50 INFO - '_': '/tools/buildbot/bin/python'} 10:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:37:50 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 10:37:50 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 10:37:50 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 10:37:50 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 10:37:50 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 10:37:50 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 10:37:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:37:52 INFO - Installing collected packages: mozsystemmonitor 10:37:52 INFO - Running setup.py install for mozsystemmonitor 10:37:52 INFO - Successfully installed mozsystemmonitor 10:37:52 INFO - Cleaning up... 10:37:52 INFO - Return code: 0 10:37:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:37:52 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')]} 10:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:52 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')]} 10:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:52 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:37:52 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 10:37:52 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 10:37:52 INFO - Using env: {'DISPLAY': ':0', 10:37:52 INFO - 'HOME': '/home/cltbld', 10:37:52 INFO - 'LANG': 'en_US.UTF-8', 10:37:52 INFO - 'LANGUAGE': 'en_US:en', 10:37:52 INFO - 'LOGNAME': 'cltbld', 10:37:52 INFO - 'MAIL': '/var/mail/cltbld', 10:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:52 INFO - 'MOZ_NO_REMOTE': '1', 10:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:52 INFO - 'NO_EM_RESTART': '1', 10:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:52 INFO - 'PWD': '/builds/slave/test', 10:37:52 INFO - 'SHELL': '/bin/bash', 10:37:52 INFO - 'SHLVL': '1', 10:37:52 INFO - 'TERM': 'linux', 10:37:52 INFO - 'TMOUT': '86400', 10:37:52 INFO - 'USER': 'cltbld', 10:37:52 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:52 INFO - '_': '/tools/buildbot/bin/python'} 10:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:52 INFO - Downloading/unpacking jsonschema==2.5.1 10:37:52 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 10:37:52 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 10:37:52 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 10:37:52 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 10:37:52 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 10:37:52 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 10:37:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:37:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:54 INFO - Downloading functools32-3.2.3-2.tar.gz 10:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:37:54 INFO - warning: no files found matching '*.txt' 10:37:54 INFO - no previously-included directories found matching 'build' 10:37:54 INFO - no previously-included directories found matching 'dist' 10:37:54 INFO - no previously-included directories found matching '.git*' 10:37:54 INFO - Installing collected packages: jsonschema, functools32 10:37:54 INFO - Running setup.py install for functools32 10:37:54 INFO - warning: no files found matching '*.txt' 10:37:54 INFO - no previously-included directories found matching 'build' 10:37:54 INFO - no previously-included directories found matching 'dist' 10:37:54 INFO - no previously-included directories found matching '.git*' 10:37:55 INFO - Successfully installed jsonschema functools32 10:37:55 INFO - Cleaning up... 10:37:55 INFO - Return code: 0 10:37:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:55 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:37:55 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 10:37:55 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 10:37:55 INFO - Using env: {'DISPLAY': ':0', 10:37:55 INFO - 'HOME': '/home/cltbld', 10:37:55 INFO - 'LANG': 'en_US.UTF-8', 10:37:55 INFO - 'LANGUAGE': 'en_US:en', 10:37:55 INFO - 'LOGNAME': 'cltbld', 10:37:55 INFO - 'MAIL': '/var/mail/cltbld', 10:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:55 INFO - 'MOZ_NO_REMOTE': '1', 10:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:55 INFO - 'NO_EM_RESTART': '1', 10:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:55 INFO - 'PWD': '/builds/slave/test', 10:37:55 INFO - 'SHELL': '/bin/bash', 10:37:55 INFO - 'SHLVL': '1', 10:37:55 INFO - 'TERM': 'linux', 10:37:55 INFO - 'TMOUT': '86400', 10:37:55 INFO - 'USER': 'cltbld', 10:37:55 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:55 INFO - '_': '/tools/buildbot/bin/python'} 10:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:55 INFO - Downloading/unpacking blobuploader==1.2.4 10:37:55 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 10:37:55 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 10:37:55 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 10:37:55 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 10:37:55 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 10:37:55 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 10:37:57 INFO - Downloading blobuploader-1.2.4.tar.gz 10:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:37:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:37:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:57 INFO - Downloading docopt-0.6.1.tar.gz 10:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:37:57 INFO - Installing collected packages: blobuploader, requests, docopt 10:37:57 INFO - Running setup.py install for blobuploader 10:37:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:37:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:37:58 INFO - Running setup.py install for requests 10:37:58 INFO - Running setup.py install for docopt 10:37:58 INFO - Successfully installed blobuploader requests docopt 10:37:58 INFO - Cleaning up... 10:37:58 INFO - Return code: 0 10:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:37: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')]} 10:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37: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')]} 10:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:58 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:37:58 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 10:37:58 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 10:37:58 INFO - Using env: {'DISPLAY': ':0', 10:37:58 INFO - 'HOME': '/home/cltbld', 10:37:58 INFO - 'LANG': 'en_US.UTF-8', 10:37:58 INFO - 'LANGUAGE': 'en_US:en', 10:37:58 INFO - 'LOGNAME': 'cltbld', 10:37:58 INFO - 'MAIL': '/var/mail/cltbld', 10:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:58 INFO - 'MOZ_NO_REMOTE': '1', 10:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:37:58 INFO - 'NO_EM_RESTART': '1', 10:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:58 INFO - 'PWD': '/builds/slave/test', 10:37:58 INFO - 'SHELL': '/bin/bash', 10:37:58 INFO - 'SHLVL': '1', 10:37:58 INFO - 'TERM': 'linux', 10:37:58 INFO - 'TMOUT': '86400', 10:37:58 INFO - 'USER': 'cltbld', 10:37:58 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:58 INFO - '_': '/tools/buildbot/bin/python'} 10:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:37:58 INFO - Running setup.py (path:/tmp/pip-uj3xR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:37:58 INFO - Running setup.py (path:/tmp/pip-2KSYrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:37:58 INFO - Running setup.py (path:/tmp/pip-XJtK61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:37:59 INFO - Running setup.py (path:/tmp/pip-_56qJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:37:59 INFO - Running setup.py (path:/tmp/pip-QouvZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:37:59 INFO - Running setup.py (path:/tmp/pip-c9SsQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:37:59 INFO - Running setup.py (path:/tmp/pip-EaNecf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:37:59 INFO - Running setup.py (path:/tmp/pip-zV3MAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:37:59 INFO - Running setup.py (path:/tmp/pip-OatB0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:37:59 INFO - Running setup.py (path:/tmp/pip-fB7ONr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:37:59 INFO - Running setup.py (path:/tmp/pip-D5EVc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:37:59 INFO - Running setup.py (path:/tmp/pip-X8Sb3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:37:59 INFO - Running setup.py (path:/tmp/pip-Eamh3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:37:59 INFO - Running setup.py (path:/tmp/pip-MHd5sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:37:59 INFO - Running setup.py (path:/tmp/pip-d_vgg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:37:59 INFO - Running setup.py (path:/tmp/pip-MufhVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:37:59 INFO - Running setup.py (path:/tmp/pip-ig7h9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:38:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:38:00 INFO - Running setup.py install for manifestparser 10:38:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Running setup.py install for mozcrash 10:38:00 INFO - Running setup.py install for mozdebug 10:38:00 INFO - Running setup.py install for mozdevice 10:38:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Running setup.py install for mozfile 10:38:00 INFO - Running setup.py install for mozhttpd 10:38:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Running setup.py install for mozinfo 10:38:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Running setup.py install for mozInstall 10:38:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:38:00 INFO - Running setup.py install for mozleak 10:38:00 INFO - Running setup.py install for mozlog 10:38:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Running setup.py install for moznetwork 10:38:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Running setup.py install for mozprocess 10:38:01 INFO - Running setup.py install for mozprofile 10:38:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Running setup.py install for mozrunner 10:38:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Running setup.py install for mozscreenshot 10:38:01 INFO - Running setup.py install for moztest 10:38:01 INFO - Running setup.py install for mozversion 10:38:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:38:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:38:01 INFO - Cleaning up... 10:38:01 INFO - Return code: 0 10:38:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38:01 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')]} 10:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:01 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')]} 10:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:01 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:38:01 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 10:38:01 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 10:38:01 INFO - Using env: {'DISPLAY': ':0', 10:38:01 INFO - 'HOME': '/home/cltbld', 10:38:01 INFO - 'LANG': 'en_US.UTF-8', 10:38:01 INFO - 'LANGUAGE': 'en_US:en', 10:38:01 INFO - 'LOGNAME': 'cltbld', 10:38:01 INFO - 'MAIL': '/var/mail/cltbld', 10:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:01 INFO - 'MOZ_NO_REMOTE': '1', 10:38:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:01 INFO - 'NO_EM_RESTART': '1', 10:38:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:01 INFO - 'PWD': '/builds/slave/test', 10:38:01 INFO - 'SHELL': '/bin/bash', 10:38:01 INFO - 'SHLVL': '1', 10:38:01 INFO - 'TERM': 'linux', 10:38:01 INFO - 'TMOUT': '86400', 10:38:01 INFO - 'USER': 'cltbld', 10:38:01 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:01 INFO - '_': '/tools/buildbot/bin/python'} 10:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:38:02 INFO - Running setup.py (path:/tmp/pip-Ztps6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:38:02 INFO - Running setup.py (path:/tmp/pip-PhTHJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:38:02 INFO - Running setup.py (path:/tmp/pip-HOxpXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:38:02 INFO - Running setup.py (path:/tmp/pip-Z9Rj6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:38:02 INFO - Running setup.py (path:/tmp/pip-BzXes8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:38:02 INFO - Running setup.py (path:/tmp/pip-mkOkrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:38:02 INFO - Running setup.py (path:/tmp/pip-trTMLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:38:02 INFO - Running setup.py (path:/tmp/pip-IOn6UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:38:02 INFO - Running setup.py (path:/tmp/pip-LPF9wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:38:02 INFO - Running setup.py (path:/tmp/pip-qLOKVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:38:02 INFO - Running setup.py (path:/tmp/pip-GMKXdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:38:02 INFO - Running setup.py (path:/tmp/pip-AAfTZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:38:02 INFO - Running setup.py (path:/tmp/pip-RTfv7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:38:02 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)) 10:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:38:03 INFO - Running setup.py (path:/tmp/pip-Y9ouw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:38:03 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)) 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:03 INFO - Running setup.py (path:/tmp/pip-xsh_lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:03 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)) 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:38:03 INFO - Running setup.py (path:/tmp/pip-bsbRCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:38:03 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)) 10:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:38:03 INFO - Running setup.py (path:/tmp/pip-eMcMJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:38:03 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)) 10:38:03 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)) 10:38:03 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)) 10:38:03 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)) 10:38:03 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)) 10:38:03 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)) 10:38:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:38:03 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 10:38:03 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 10:38:03 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 10:38:03 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 10:38:03 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 10:38:03 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 10:38:05 INFO - Downloading blessings-1.6.tar.gz 10:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:38:05 INFO - Installing collected packages: blessings 10:38:05 INFO - Running setup.py install for blessings 10:38:05 INFO - Successfully installed blessings 10:38:05 INFO - Cleaning up... 10:38:05 INFO - Return code: 0 10:38:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:38:06 INFO - Reading from file tmpfile_stdout 10:38:06 INFO - Current package versions: 10:38:06 INFO - argparse == 1.2.1 10:38:06 INFO - blessings == 1.6 10:38:06 INFO - blobuploader == 1.2.4 10:38:06 INFO - docopt == 0.6.1 10:38:06 INFO - functools32 == 3.2.3-2 10:38:06 INFO - jsonschema == 2.5.1 10:38:06 INFO - manifestparser == 1.1 10:38:06 INFO - mozInstall == 1.12 10:38:06 INFO - mozcrash == 0.17 10:38:06 INFO - mozdebug == 0.1 10:38:06 INFO - mozdevice == 0.48 10:38:06 INFO - mozfile == 1.2 10:38:06 INFO - mozhttpd == 0.7 10:38:06 INFO - mozinfo == 0.9 10:38:06 INFO - mozleak == 0.1 10:38:06 INFO - mozlog == 3.2 10:38:06 INFO - moznetwork == 0.27 10:38:06 INFO - mozprocess == 0.22 10:38:06 INFO - mozprofile == 0.28 10:38:06 INFO - mozrunner == 6.11 10:38:06 INFO - mozscreenshot == 0.1 10:38:06 INFO - mozsystemmonitor == 0.3 10:38:06 INFO - moztest == 0.7 10:38:06 INFO - mozversion == 1.4 10:38:06 INFO - psutil == 3.1.1 10:38:06 INFO - requests == 1.2.3 10:38:06 INFO - wsgiref == 0.1.2 10:38:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38: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')]} 10:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38: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')]} 10:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:06 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:38:06 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 10:38:06 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 10:38:06 INFO - Using env: {'DISPLAY': ':0', 10:38:06 INFO - 'HOME': '/home/cltbld', 10:38:06 INFO - 'LANG': 'en_US.UTF-8', 10:38:06 INFO - 'LANGUAGE': 'en_US:en', 10:38:06 INFO - 'LOGNAME': 'cltbld', 10:38:06 INFO - 'MAIL': '/var/mail/cltbld', 10:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:06 INFO - 'MOZ_NO_REMOTE': '1', 10:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:06 INFO - 'NO_EM_RESTART': '1', 10:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:06 INFO - 'PWD': '/builds/slave/test', 10:38:06 INFO - 'SHELL': '/bin/bash', 10:38:06 INFO - 'SHLVL': '1', 10:38:06 INFO - 'TERM': 'linux', 10:38:06 INFO - 'TMOUT': '86400', 10:38:06 INFO - 'USER': 'cltbld', 10:38:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:06 INFO - '_': '/tools/buildbot/bin/python'} 10:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 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)) 10:38:06 INFO - Cleaning up... 10:38:06 INFO - Return code: 0 10:38:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:38: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')]} 10:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38: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')]} 10:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:06 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:38:06 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 10:38:06 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 10:38:06 INFO - Using env: {'DISPLAY': ':0', 10:38:06 INFO - 'HOME': '/home/cltbld', 10:38:06 INFO - 'LANG': 'en_US.UTF-8', 10:38:06 INFO - 'LANGUAGE': 'en_US:en', 10:38:06 INFO - 'LOGNAME': 'cltbld', 10:38:06 INFO - 'MAIL': '/var/mail/cltbld', 10:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:06 INFO - 'MOZ_NO_REMOTE': '1', 10:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:06 INFO - 'NO_EM_RESTART': '1', 10:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:06 INFO - 'PWD': '/builds/slave/test', 10:38:06 INFO - 'SHELL': '/bin/bash', 10:38:06 INFO - 'SHLVL': '1', 10:38:06 INFO - 'TERM': 'linux', 10:38:06 INFO - 'TMOUT': '86400', 10:38:06 INFO - 'USER': 'cltbld', 10:38:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:06 INFO - '_': '/tools/buildbot/bin/python'} 10:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:38:06 INFO - Cleaning up... 10:38:06 INFO - Return code: 0 10:38:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:38: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')]} 10:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38: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')]} 10:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:06 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 0x7f800c030f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x112e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f9350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, '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': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', '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 10:38:06 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 10:38:06 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 10:38:06 INFO - Using env: {'DISPLAY': ':0', 10:38:06 INFO - 'HOME': '/home/cltbld', 10:38:06 INFO - 'LANG': 'en_US.UTF-8', 10:38:06 INFO - 'LANGUAGE': 'en_US:en', 10:38:06 INFO - 'LOGNAME': 'cltbld', 10:38:06 INFO - 'MAIL': '/var/mail/cltbld', 10:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:06 INFO - 'MOZ_NO_REMOTE': '1', 10:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:06 INFO - 'NO_EM_RESTART': '1', 10:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:06 INFO - 'PWD': '/builds/slave/test', 10:38:06 INFO - 'SHELL': '/bin/bash', 10:38:06 INFO - 'SHLVL': '1', 10:38:06 INFO - 'TERM': 'linux', 10:38:06 INFO - 'TMOUT': '86400', 10:38:06 INFO - 'USER': 'cltbld', 10:38:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:06 INFO - '_': '/tools/buildbot/bin/python'} 10:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:06 INFO - Downloading/unpacking flake8 10:38:06 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 10:38:06 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 10:38:06 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 10:38:06 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 10:38:06 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 10:38:06 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 10:38:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:38:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:38:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 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 10:38:08 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 10:38:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:38:09 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 10:38:09 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 10:38:09 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 10:38:09 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 10:38:09 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 10:38:09 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 10:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:38:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:38:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:38:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:38:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:38:09 INFO - no previously-included directories found matching 'docs/_build' 10:38:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:38:09 INFO - Running setup.py install for pep8 10:38:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:38:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:38:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:38:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:38:09 INFO - no previously-included directories found matching 'docs/_build' 10:38:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:38:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:38:09 INFO - Cleaning up... 10:38:09 INFO - Return code: 0 10:38:09 INFO - Running post-action listener: _resource_record_post_action 10:38:09 INFO - Running post-action listener: _start_resource_monitoring 10:38:09 INFO - Starting resource monitoring. 10:38:09 INFO - ##### 10:38:09 INFO - ##### Finished create-virtualenv step (success) 10:38:09 INFO - ##### 10:38:09 INFO - ##### 10:38:09 INFO - ##### Running install step. 10:38:09 INFO - ##### 10:38:09 INFO - Running pre-action listener: _resource_record_pre_action 10:38:09 INFO - Running main action method: install 10:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:38:09 INFO - Reading from file tmpfile_stdout 10:38:09 INFO - Detecting whether we're running mozinstall >=1.0... 10:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:38:09 INFO - Reading from file tmpfile_stdout 10:38:09 INFO - Output received: 10:38:09 INFO - Usage: mozinstall [options] installer 10:38:09 INFO - Options: 10:38:09 INFO - -h, --help show this help message and exit 10:38:09 INFO - -d DEST, --destination=DEST 10:38:09 INFO - Directory to install application into. [default: 10:38:09 INFO - "/builds/slave/test"] 10:38:09 INFO - --app=APP Application being installed. [default: firefox] 10:38:09 INFO - mkdir: /builds/slave/test/build/application 10:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:38:25 INFO - Reading from file tmpfile_stdout 10:38:25 INFO - Output received: 10:38:25 INFO - /builds/slave/test/build/application/firefox/firefox 10:38:25 INFO - Running post-action listener: _resource_record_post_action 10:38:25 INFO - ##### 10:38:25 INFO - ##### Finished install step (success) 10:38:25 INFO - ##### 10:38:25 INFO - ##### 10:38:25 INFO - ##### Running run-tests step. 10:38:25 INFO - ##### 10:38:25 INFO - Running pre-action listener: _resource_record_pre_action 10:38:25 INFO - Running main action method: run_tests 10:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:38:25 INFO - Python 2.7.3 10:38:25 INFO - Return code: 0 10:38:25 INFO - grabbing minidump binary from tooltool 10:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:38:25 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 0x12881c0>, '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 0x12890c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1289550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:38:25 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 10:38:25 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 10:38:25 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 10:38:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:38:25 INFO - Return code: 0 10:38:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:38:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:38:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:38:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:38:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:38:26 INFO - Return code: 0 10:38:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:38:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:38:26 INFO - Using env: {'DISPLAY': ':0', 10:38:26 INFO - 'HOME': '/home/cltbld', 10:38:26 INFO - 'LANG': 'en_US.UTF-8', 10:38:26 INFO - 'LANGUAGE': 'en_US:en', 10:38:26 INFO - 'LOGNAME': 'cltbld', 10:38:26 INFO - 'MAIL': '/var/mail/cltbld', 10:38:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:38:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:26 INFO - 'MOZ_NO_REMOTE': '1', 10:38:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:38:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:38:26 INFO - 'NO_EM_RESTART': '1', 10:38:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:26 INFO - 'PWD': '/builds/slave/test', 10:38:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:38:26 INFO - 'SHELL': '/bin/bash', 10:38:26 INFO - 'SHLVL': '1', 10:38:26 INFO - 'TERM': 'linux', 10:38:26 INFO - 'TMOUT': '86400', 10:38:26 INFO - 'USER': 'cltbld', 10:38:26 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398', 10:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:26 INFO - '_': '/tools/buildbot/bin/python'} 10:38:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:38:27 INFO - mozversion application_buildid: 20160603092112 10:38:27 INFO - mozversion application_changeset: f73e91c7bd1fe252c32004934f960b5bf27204cf 10:38:27 INFO - mozversion application_display_name: Nightly 10:38:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:38:27 INFO - mozversion application_name: Firefox 10:38:27 INFO - mozversion application_remotingname: firefox 10:38:27 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 10:38:27 INFO - mozversion application_vendor: Mozilla 10:38:27 INFO - mozversion application_version: 49.0a1 10:38:27 INFO - mozversion platform_buildid: 20160603092112 10:38:27 INFO - mozversion platform_changeset: f73e91c7bd1fe252c32004934f960b5bf27204cf 10:38:27 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 10:38:27 INFO - mozversion platform_version: 49.0a1 10:38:27 INFO - using testdate: 1464975507 10:38:27 INFO - actual date: 1464975507 10:38:27 INFO - starting webserver on 'localhost:32930' 10:38:27 INFO - SUITE-START | Running 2 tests 10:38:27 INFO - TEST-START | dromaeo_css 10:38:27 INFO - operating with platform_type : linux_ 10:38:27 INFO - Initialising browser for dromaeo_css test... 10:38:27 INFO - TEST-INFO | started process 26511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm_uVw5/profile http://localhost:32930/getInfo.html) 10:38:31 INFO - PROCESS | 26511 | __metrics Screen width/height:1600/1200 10:38:31 INFO - PROCESS | 26511 | colorDepth:24 10:38:31 INFO - PROCESS | 26511 | Browser inner width/height: 1024/697 10:38:31 INFO - PROCESS | 26511 | __metrics 10:38:33 ERROR - PROCESS | 26511 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:38:34 INFO - TEST-INFO | 26511: exit 0 10:38:34 INFO - Browser initialized. 10:38:34 INFO - Running cycle 1/1 for dromaeo_css test... 10:38:34 INFO - TEST-INFO | started process 26592 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm_uVw5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:46 INFO - PROCESS | 26592 | RSS: Main: 157851648 10:38:46 INFO - PROCESS | 26592 | 10:39:36 INFO - PROCESS | 26592 | 1464975576951 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] 10:41:49 INFO - PROCESS | 26592 | Cycle 1(1): loaded http://localhost:32930/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32930/tests/dromaeo/cssquery-ext.html) 10:41:51 INFO - PROCESS | 26592 | RSS: Main: 267702272 10:41:51 INFO - PROCESS | 26592 | 10:44:53 INFO - PROCESS | 26592 | Cycle 1(1): loaded http://localhost:32930/tests/dromaeo/cssquery-ext.html (next: http://localhost:32930/tests/dromaeo/cssquery-jquery.html) 10:44:54 INFO - PROCESS | 26592 | RSS: Main: 238919680 10:44:54 INFO - PROCESS | 26592 | 10:47:56 INFO - PROCESS | 26592 | Cycle 1(1): loaded http://localhost:32930/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32930/tests/dromaeo/cssquery-mootools.html) 10:47:57 INFO - PROCESS | 26592 | RSS: Main: 241467392 10:47:57 INFO - PROCESS | 26592 | 10:50:59 INFO - PROCESS | 26592 | Cycle 1(1): loaded http://localhost:32930/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32930/tests/dromaeo/cssquery-prototype.html) 10:50:59 INFO - PROCESS | 26592 | RSS: Main: 240254976 10:50:59 INFO - PROCESS | 26592 | 10:54:02 INFO - PROCESS | 26592 | Cycle 1(1): loaded http://localhost:32930/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32930/tests/dromaeo/cssquery-yui.html) 10:54:03 INFO - PROCESS | 26592 | RSS: Main: 227733504 10:54:03 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | Cycle 1(1): loaded http://localhost:32930/tests/dromaeo/cssquery-yui.html (next: http://localhost:32930/tests/dromaeo/cssquery-dojo.html) 10:57:06 INFO - PROCESS | 26592 | RSS: Main: 275283968 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | __start_tp_report 10:57:06 INFO - PROCESS | 26592 | _x_x_mozilla_page_load 10:57:06 INFO - PROCESS | 26592 | _x_x_mozilla_page_load_details 10:57:06 INFO - PROCESS | 26592 | |i|pagename|runs| 10:57:06 INFO - PROCESS | 26592 | |0;dojo.html;2676.4379480309135;2677.8661066946647;2716.157991022783;2727.3454370950976;2735.49393362228;4585.082983403316;4636.443626764784;4701.27130294804;4768.546988036135;4845.491223421543;4680.110778952006;4743.5256474352545;4751.477337492882;4760.500147484516;4777.283407488879;4312.374705667677;4341.2836881914445;4411.448568928884;4444.577765112309;4508.188526065307;4456.799444025027;4466.955330446686;4469.396631454761;4500.21246281901;4522.819087236507;4209.095044565433;4237.385579091046;4320.589543993316;4347.84782532613;4389.275769498045;4263.339182426729;4270.722403043823;4280.058387154852;4298.4197133387215;4329.328954012133;4320;4323.2434323993175;4324.654027677778;4364.978175109136;4373.562643735633;4497.055618320124;4547.18150732868;4610.700304480198;4636.953630463686;4650.860474185746;5119.206522988943;5128.384593848725;5141.203113517411;5146.948530514722;5156.226566015128;5147.588192944556;5154.92267615986;5156.458571849977;5162.406323272797;5168.638195326329;4589.793459294307;4643.698159619614;4692.95307046932;4696.178168820443;4722.645801564897;5115.18157094863;5143.27994080829;5185.118529849935;5196.142636465017;5236.73816309183;4792.233242681224;4963.726995015309;5011.674241174312;5014.924776128361;5019.623528235397;4975.154223781966;4993.90012199754;5011.348524691767;5040.974795126001;5088.287639730441;5023.321851550067;5031.698098114125;5034.697918124924;5036.64743467961;5084.466131056186;4994.575461085776;4998.375203099613;5003.656479929147;5005.799768009311;5014.89970200594;4168.937465938014;4209.494860616745;4214.704970652025;4264.189803937304;4307.56924307573;3802.1825307558456;3833.1758671885677;3851.8651847185215;3895.474110995036;3905.414187871785;4345.891352716208;4347.434833471629;4367.912641747147;4384.342348647665;4387.3857659927635;4868.853934381974;4888.266759986031;5150.0472412603785;5150.871228219325;5202.531772140525;4284.721493102938;4328.329108988112;4344.261475549164;4346.6088052075465;4355.194289056535;4539.001419687664;4574.6340292776495;4577.1074640444795;4624.44506659203;4627.976860115745;4589.357489951476;4705.882352941204;4762.571368576775;4801.7118972861745;4805.687630304019;5034.270030845581;5082.898342033065;5110.361204849245;5114;5156.56169225605;4777.044591081728;4787.904241915073;4791.377120974251;4797.9040419192115;4837.588804951619;4816.686915350491;4922.532359425849;4962.181240095344;5004.749762511787;5053.797848086036;5044.167712327425;5055.191169412877;5063.3417655704525;5066.36670416198;5092.694438333712;4923.876903077452;4925.778339974638;4927.507249275044;4935.62982776286;4962.280469331999;4758.4765675776325;4848.80604775805;4891.584215341736;4921.581665558468;4951;4835.105505481497;4841.298011788342;4890.510948905081;4891.095147397743;4891.755412229303;4956.430010548689;4956.504349565014;4966.528179822911;4984.42679091909;5005.949940500694;4724.834630787906;4802.111609352281;4834.516548345137;4846.079244943449;4879.463259041431 10:57:06 INFO - PROCESS | 26592 | |1;ext.html;534.4895624678433;594.3224723814852;595.2202614574907;599.0325624117047;614.5237440983238;27567.172984810462;40105.59894401055;40923.59076409239;40951.180976380456;40975.18049639013;41472.79263603678;41954.790226048826;42002;42045.57954420455;42080.57919420805;7661.042369703787;7791.649375778075;7799.142094369643;7799.454038217327;7800.18098099697;7841.490303130278;7984.560849153294;7989.400794940403;7990.320822730075;7991.081025682034;7942.642581083836;8019.318357939582;8022.759317220463;8024.879626805603;8028.518288902685;7907.16974717655;7964.084130325029;7973.960130199312;7974.681012759483;7976.999999999971;7833.804154896173;7926.920730792734;7929.563873986928;7930.841383172362;7932.881006784903;37830.24339513194;38208.61791382078;38283.425748613794;38310;38334.61665383366;3039.4832878410502;3141.2618034761813;3182.904512864595;3189.5375170600364;3241.546183534307;29217.561736573967;29623.259418514495;29770.85114574435;29981.400371992437;30179.698203018128;2892.4359749848786;2900.9709902900913;2914.606528118698;2959.689232630586;3022.6523949745842;276747;280760;280786;280814.5959270211;281301;32101;57082;57578.4242157573;57590.7120464395;57592.39260859121;32764.836175818968;34444.827775861464;34455.65544344574;34463.13842153966;34472.31055378878;13910.86089139116;14056.578302650936;14066.718665626835;14070.929645351707;14075.859241407456;11640.65078047643;11751.118666100077;11762;11762.35307058103;11785.410729463492;7978.441509094308;7989.440739148204;8000.479968802009;8061.1938806118915;8080.959595202104;3049.2224482756756;3064.785465017472;3068.647105582842;3091.7990330628436;3145.811251324928;933.3419938943028;935.9625614975324;959.5586030425943;1003.7540802503433;1018.2719355660738;27141.728582714313;27745.861270693516;27929.92770795211;28322.942892538915;28490;931.3340961212765;976.8046390721742;994.3288280410694;998.8651532043082;1028.7115784665727;14732.337044832793;14764.187969661556;14836.700311451743;15041.548753537412;15184.240787960558;408606;410656;412007;413067;413968;49386.75306623516;49866.50133498691;49904;49948.75025624849;49972;1102.685734565645;1177.264209868832;1187.186777057701;1218.6770505815966;1221.1451983611507;28241.15276541707;28822.685391180283;29000.564991524723;29277.560836587894;29343;40920.38619420651;41144.38283425691;41359.793201033805;41363;41485.1702965945;306520;307821;307919;307963;307999;21520.246791362228;24209;25249.87375063113;25255.494890101727;25391;41853.58146418497;42105.78947105367;42345;43327;43392.78303608462;35564;36158;36367.63632363748;36544.45183322306;36633.81683091674;35977;36467;36526.26947461089;36565.45151822778;36605.81697091497;35016.64983350134;35614.8219258902;36325.09187270339;36357.63642363543;36419.45370819493;30819.383612328078;32608.184795380304;32694.83652581817;32976.34047318992;33332.333353333284;24704.752952470244;24880;24912.252632421056;25363.61954570646;25566.872165639797 10:57:06 INFO - PROCESS | 26592 | |2;jquery.html;372.11808015004436;374.65157403614626;376.67229510326;389.52867030892617;390.5898806253435;15648.687026259468;17152.742708859376;17282.740758888594;17507.124643767824;17580.912095439537;16099.356025758956;16828.495145145636;16844.494665160033;16845;17409.04250266235;5524.99999999999;5815.825525234229;5861.589688721791;5901.144334071557;5903.763849446017;4064.512258528966;4308.784560771949;6609.735610575572;6977;6978.895316570255;6961.512694111414;6992.405645520112;7055.753048643299;7085.539439936413;7089.432845372384;6820.35206655367;6962.617056061915;6962.860742785116;7012.193597736249;7027.227005029468;6172.537059720494;6529.510286728514;6532.934670653281;6535.183102112236;6539;22280.554388912293;22496.887515562317;23002;23217.767822321897;23232.883835580884;1980.2079168332639;2155.8275337972923;2194.9341519754435;2361.480474295652;2396.9640455393182;14072.2963851807;14084.43662253519;14119.364628591738;14144.36350364236;14640.033914366204;1823.5167680564662;1842.2630947620926;1954.1108795498014;1979.257778333125;2018.1927229108328;79715.20284797136;83256.58371708164;83372;83494.58252708698;83890;16992.74510882338;17065.7440138398;17100.74348884768;17136.143192840307;17257.56856078608;29373.559396609067;29417.411651766844;29717.85141074281;29759.85120074386;29799.72203057662;7882.330001949898;8262.42163048581;8281.95859020701;8289.626966786509;8381.245687888118;6662.267150613428;6669.799906002828;6755.18937727466;6762.425193858478;6814.693338799667;7193;8078.192180781993;8428.831423371621;8536.359773017048;8639.179277968582;2896.6089577907132;3110.7977981431136;3280.3603297356785;3317.5023746437746;3454.2573346730574;773.833625770462;854.8717692346198;861.7759382560579;871.063606622883;871.4945331707594;16118.597035073983;16728.41450549225;17082.145892705565;17089.31642734277;17183.914080429517;843.6583183810567;870.4472659861034;888.0187392930848;893.8167675626481;900.8828852249165;9315.673951411669;9585.233181345475;9596.424214547149;9636.518174091267;9715.951420242995;353846;385029;385876;386274;388132;74278.62860685663;76224.61887690525;76354.61822690851;76391.23608763953;76412.61793690996;915.7115508614899;916.326500022483;929.5677509957902;941.6233506597416;944.414463032924;14881.702365952404;14939.891529829383;15011.32449039774;16292.51122466328;16329.755053673967;29201.561976569945;33500.83249583736;36067.45898811572;36316.81841590775;36936;47060.7646961763;47755.28367074427;47859;48560;48765.75617121892;7613.581253031137;7685.692572297045;8014.599270036359;8014.83970320602;8385.245327920517;7747;8656.437331573428;11524.308541487537;11708.238964467282;11988.460519276829;9988.60045598197;10211.438370889673;10352.741181470523;10448.32085914413;10459.790804183722;4707.670463067552;8475.957620211859;9356.95321523388;10390.740231494274;10798.352098874093;9808.264380171659;10226.437545934747;10334;10472.581096756045;10682;7334.339909408179;7457.067866516685;7527.473076884566;7622.9237707622215;7652.1582625912215;9323.300752443458;9722.368046076983;9917.900820991697;9931.950340248253;10107.444090575089 10:57:06 INFO - PROCESS | 26592 | |3;mootools.html;1208.1784386617096;1361.8025386318984;1375.2848518770234;1392.456941792701;1430.9999999999993;6137.355577664348;7308.853822923545;7568.281013303727;7568.772936811891;7728.188540203268;7435.367993720541;7580.39356851452;7587.696492140308;7588.772336829904;7594.9240507594905;2249.966250506244;2279.9316020519327;2282.395165281202;2282.5891339558957;2287.0737351372723;2205.8014778669917;2234.7094877665963;2259.0963614554225;2259.6836442898007;2260.333201705501;2222.1555808792637;2226.92205772798;2232.3749350181974;2232.598132336179;2255.041607316884;2204.8015678588845;2219.833512486562;2221.9666704999436;2226.877521736296;2229.0972156276653;2214.568159208955;2218.3566765637947;2221.2336743823357;2222.588821068108;2224.443889027743;7248;7465.888011679829;7564.621768911533;7606.429517786189;7610.1628820829665;4307.590778876001;4416.53626369233;4418.80115394808;4421.358902959085;4434.379186913835;2794.203651959182;2867.569864520318;2908.941821163565;2916.066858605247;2916.9270768230754;924.3206243411088;952.5761036338832;960.7742180587557;961.4952150121172;967.5404183013083;9990.85013724802;10045.698629041068;10110.69667909964;10121;10176.491175441271;4020.6783457323927;4123.649489793401;4185.790710464465;4196.643285320721;4196.853110141192;8058.395620328499;8106.716264930699;8110.472819266729;8119.228673276029;8121.5533145677555;4313.935290970575;4334.9349759752995;4369.344598310279;4374.081442897027;4389.539098394686;3389.9322013560086;3562.358775420449;3859.845606175778;3860.96139038606;3982.78094704794;3461.065512311712;3470.7917524948584;3551.9644803551632;3577.982110089433;3587.0852932502567;1059.7668512927144;1062.7236918401272;1066.1151244467073;1066.664000839733;1070.71626019105;661.8742438936587;725.4957804325943;729.6023667101442;813.1705660226654;855.6791203298762;4869.220924652039;5108.259302401206;5183.326167598264;5190.714510701948;5267.894642107136;825.6449726617492;831.3224721851672;834.4534330013851;867.1545243387646;867.5055218525506;3592.4431713084514;3661.8901432957055;3664.2121943782213;3719.0702324418894;3725.720570957189;8943.23982461498;9213.861792073125;9301.209397201179;9305.674301399418;9311.580978855967;9164.90835091654;9166.816663666823;9195.26437884981;9206.723798286062;9213.585388657526;6635.10426092481;6978.406835418945;6997.370236678723;7115.395191408685;7116.92883071173;5089.9745501272255;5336.67979921206;5440.809571664972;5597.608167428404;5704.258446401941;8148;8441.697931289267;8541.658333666584;8597.957010214908;8609.48343099391;8484.533350665526;8685.826283474422;8697.608607612547;8708.303335733253;8726.912730872864;6402.487800975818;6456.612603243821;6522.934770652233;6524.9673751630935;6526.118973938457;6936.757213497705;7012.859742805217;7076.433885289061;7079.22128565847;7084.57492550428;6262.46769024638;6380.457661098859;6431.067495213261;6493.935060649522;6654.900176497454;6333;6344.492440604833;6447.70985305653;6647.036179754005;6682.331766823473;5481.808136715196;5954.10688396744;6079.969600152148;6138.508919286357;6138.631682099089;4729.787159577759;4763.452202996561;4771.23660214364;4793.496682848251;4879.512048795092;4764.928526072181;4788.569028787426;4800.639952003544;4849.224124140121;4909.9263511048075 10:57:06 INFO - PROCESS | 26592 | |4;prototype.html;306.2435783614472;307.0956034478462;312.42981558655447;317.6902520042958;317.95230715392705;4753.358296629958;4773.164696178168;4783;4788.473267940528;4804.063207674505;4691.460482044566;4741.668083234175;4755.167845627018;4761.214399624067;4763.90472190556;3561.376759067166;3628.5827129880136;3682.1715114099247;3715.1269451678836;3768.5854555998817;3715.4241092630687;3736.75711078779;3738.8504459821575;3748.868789592365;3753.1555400035045;3712.4060150375944;3720.479132921393;3732.552093748754;3743.66307032367;3746.662800347968;3536.284901822614;3600.2799440111953;3717.4052151655874;3740.738148329618;3741.0834345585235;3297.6314829345793;3304.8843290484956;3309.2223327518013;3313.5692359993045;3326.3679900818765;4505.36924830524;4548.226801443762;4553.476350219701;4597.195490789099;4618.445786505641;4156.168766246763;4195.845376963591;4268.9146217075795;4275.572442755731;4282.357646353072;4892.9510704892855;4903.730294833783;4933.407991041098;4934.580560652349;4935.5804756595735;3581.6418358164237;3584.982075089608;3611.855525778966;3619.2580520993133;3620.818959052037;4986.252062190664;5152.690838549699;5175.534201921845;5181.274621552986;5210.951593317914;3673.779573225615;3698.1863989922176;3703.407454807218;3706.3513885070006;3716.6283371663158;3743.6069212732814;3771.26460340231;3780.4707340972286;3789.7915614640906;3793.563740169916;4244.087521182961;4257.74453532789;4259.21204577148;4272.16692744918;4277.401163837066;4794.4486384065585;4940.654154209243;4952.4304704959395;4954.207326827763;4987.501249874984;1264.2035517623838;1278.878506541877;1280.020784100164;1282.6985658370274;1285.9807102893465;844.7085755414372;848.8243782361351;850.2517784349733;860.2214995429147;880.3837313880309;4333.544977777288;4342.153280110411;4348.913021739548;4361.971683167342;4368.759718215465;5050.924236136461;5052.823151189691;5064.0756512525;5069.5690866276045;5076.923846142311;4239.144884223633;4331.981984233703;4339.82640694375;4355.651547876162;4365.738055716667;2579.948401031969;2687.87904544296;2696.7438093381097;2698.662667166604;2705.485957668076;4723.220668589644;4738.005018945991;4750.097481478508;4784.784684689197;4795.712257264575;4893.535114164149;5147.485251474823;5165.483451654804;5166.9999999999245;5186.196139598368;4741.051789642016;4772.164871147535;4775.928361074656;4801.351817504592;4802.207635740134;5019.824306149268;5031.874203144951;5049.267856160763;5060.49395060491;5078.704930242764;4781.42622885256;4905.975470122627;4935.728534930613;4943.8629115302965;4961.181405068123;4845.5881250094135;4859.635527335393;4874.488178741323;4888.291197776373;4909.607231421549;3644.890653280406;3656.323580137683;3666.2667466507337;3695.870644527429;3704.814759261972;5114.79540818378;5225.529702326809;5233.895322093461;5258.553022992935;5260.710660913686;4967.577755890789;4968.180250258667;4974.179260422135;4982.601391888567;5000.449950505515;4978.576820970258;4997.425296090851;5016.197408414636;5018.648694591344;5052.1663925451885;4701.811927522997;4893.314935908905;4905.5094490550655;4908.950910490849;4917.065757506205;4751.954569994596;4855.611551075976;4864.781084851261;4875.219964805614;4978.601711863114;4802.711837289774;4814.711117332971;4842.709437433765;4910.975445122752;4933.309336692938 10:57:06 INFO - PROCESS | 26592 | |5;yui.html;660.7588230295942;687.6767919078034;698.4796326736579;702.1749444402825;750.53091817614;12277.877221227796;13107.082504224683;13127.737445251114;13187.6703082423;13198.142120762155;12188.634340969786;12304.93847530761;12313.199642023263;12322;12325.876741232585;617.3363634093345;749.6888791151665;769.5844244108175;780.9258120478545;782.0849605961039;779.3375630713876;782.2568559868148;785.1128225105623;785.2657764989752;785.7053604898164;834.0866750907777;834.4826207751203;834.8956380452444;834.9248567628912;835.6699103854005;646.5474168082337;797.5175019113469;824.9133840946674;826.1078035721406;828.5650033732278;607.8146165419545;608.0484042473515;608.1546650156287;608.4128815692877;608.5496732418023;11481.655550333504;11507.07943364529;11507.654770356903;11515.942420287845;11905.226160299553;12100;12573.937130314382;12591.055670824724;12725.681857953532;12954.139220401592;10555.313904596176;10566.471676416148;10567.78864422715;10772.353658780497;10928.836067458995;4093.9385909211387;4134.3178375567995;4138.937915931264;4171.374293855915;4259.51015633203;56747.71626141884;57155.71422142904;57246.713766430905;59512;59543.40456595422;10190.337628054152;10406.895931040744;10453.163746900229;10528.947355263175;10536.367817930795;18956.336528221447;18998;19008.42974710761;19054.04729763534;19079.427617171506;6160.1375807387;6268.62388256697;6269.529785266123;6270.561060725706;6447.4197322241225;3610.223801882555;3682.447632855093;3718.6467285607823;3725.8882233533036;3734.066483379155;2660.5743081107316;2740.1916434651826;2741.08173761792;2750.587411888233;2753.8898444062415;514.1901505129437;545.1441237257546;553.0294333444859;556.0991194265316;559.1556749308573;525.7397588193792;557.4926816596877;605.1135087097423;623.9438450539383;647.6535053746318;8550.230479256898;8666.739997800076;8684.131586841266;8729.345299102593;8736.650533978696;514.2902794144057;568.8236646639555;575.0999685492192;597.2146627185238;610.487190759764;4159.708820382544;4298.161858437637;4353.238183317907;4373.436377597805;4387.934180987288;57515;91401;94781.0521894786;101130.4943475278;101252.49373753232;51756.482435175916;55284.44715552873;81749.18250817535;82504.58747706223;88248.11751882527;46071.76964115158;54480.45519544833;75229.24770752332;76464;76877.231227687;8118.391120665856;8267.131951145284;8283.50298982063;8332.958335208285;8367.58162091905;45005.32492012557;46017.30974035325;47559.286610700176;47684.7615761919;48318.516814831404;64332.67833660802;67617;68280.3171968274;68298.65850670714;68944.31055689379;10107.848382274418;10165.644202452879;10332.225083118947;10409.27135100566;10601.893981060091;11356.886431135583;11655.53377864876;11975.820362694742;12066.818997714865;12176.086793490347;10687.038166564735;10717.89282107169;11180.496877640688;11193.552257909592;11195.10439164881;10390;10689.358638481715;10812.405317707287;10814.080803131821;10836.729081773019;10310.484475776031;10530.05229529346;10822.56709731602;11106.944465277622;11215.327080375204;7997.462381792765;8114.148014458633;8226.424150309422;8335.833283334421;8386;8839;8861.867071994055;9018.909810901807;9056.864147037932;9117.133872282122 10:57:06 INFO - PROCESS | 26592 | __end_tp_report 10:57:06 INFO - PROCESS | 26592 | __start_cc_report 10:57:06 INFO - PROCESS | 26592 | _x_x_mozilla_cycle_collect,1025 10:57:06 INFO - PROCESS | 26592 | __end_cc_report 10:57:06 INFO - PROCESS | 26592 | __startTimestamp1464976626673__endTimestamp 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | ------- Summary: start ------- 10:57:06 INFO - PROCESS | 26592 | Number of tests: 6 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | [#0] dojo.html Cycles:165 Average:4675.12 Median:4792.23 stddev:479.41 (10.0%) stddev-sans-first:454.52 10:57:06 INFO - PROCESS | 26592 | Values: 2676.4 2677.9 2716.2 2727.3 2735.5 4585.1 4636.4 4701.3 4768.5 4845.5 4680.1 4743.5 4751.5 4760.5 4777.3 4312.4 4341.3 4411.4 4444.6 4508.2 4456.8 4467.0 4469.4 4500.2 4522.8 4209.1 4237.4 4320.6 4347.8 4389.3 4263.3 4270.7 4280.1 4298.4 4329.3 4320.0 4323.2 4324.7 4365.0 4373.6 4497.1 4547.2 4610.7 4637.0 4650.9 5119.2 5128.4 5141.2 5146.9 5156.2 5147.6 5154.9 5156.5 5162.4 5168.6 4589.8 4643.7 4693.0 4696.2 4722.6 5115.2 5143.3 5185.1 5196.1 5236.7 4792.2 4963.7 5011.7 5014.9 5019.6 4975.2 4993.9 5011.3 5041.0 5088.3 5023.3 5031.7 5034.7 5036.6 5084.5 4994.6 4998.4 5003.7 5005.8 5014.9 4168.9 4209.5 4214.7 4264.2 4307.6 3802.2 3833.2 3851.9 3895.5 3905.4 4345.9 4347.4 4367.9 4384.3 4387.4 4868.9 4888.3 5150.0 5150.9 5202.5 4284.7 4328.3 4344.3 4346.6 4355.2 4539.0 4574.6 4577.1 4624.4 4628.0 4589.4 4705.9 4762.6 4801.7 4805.7 5034.3 5082.9 5110.4 5114.0 5156.6 4777.0 4787.9 4791.4 4797.9 4837.6 4816.7 4922.5 4962.2 5004.7 5053.8 5044.2 5055.2 5063.3 5066.4 5092.7 4923.9 4925.8 4927.5 4935.6 4962.3 4758.5 4848.8 4891.6 4921.6 4951.0 4835.1 4841.3 4890.5 4891.1 4891.8 4956.4 4956.5 4966.5 4984.4 5005.9 4724.8 4802.1 4834.5 4846.1 4879.5 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | [#1] ext.html Cycles:180 Average:47670.75 Median:36341.36 stddev:89246.11 (245.6%) stddev-sans-first:89426.30 10:57:06 INFO - PROCESS | 26592 | Values: 534.5 594.3 595.2 599.0 614.5 27567.2 40105.6 40923.6 40951.2 40975.2 41472.8 41954.8 42002.0 42045.6 42080.6 7661.0 7791.6 7799.1 7799.5 7800.2 7841.5 7984.6 7989.4 7990.3 7991.1 7942.6 8019.3 8022.8 8024.9 8028.5 7907.2 7964.1 7974.0 7974.7 7977.0 7833.8 7926.9 7929.6 7930.8 7932.9 37830.2 38208.6 38283.4 38310.0 38334.6 3039.5 3141.3 3182.9 3189.5 3241.5 29217.6 29623.3 29770.9 29981.4 30179.7 2892.4 2901.0 2914.6 2959.7 3022.7 276747.0 280760.0 280786.0 280814.6 281301.0 32101.0 57082.0 57578.4 57590.7 57592.4 32764.8 34444.8 34455.7 34463.1 34472.3 13910.9 14056.6 14066.7 14070.9 14075.9 11640.7 11751.1 11762.0 11762.4 11785.4 7978.4 7989.4 8000.5 8061.2 8081.0 3049.2 3064.8 3068.6 3091.8 3145.8 933.3 936.0 959.6 1003.8 1018.3 27141.7 27745.9 27929.9 28322.9 28490.0 931.3 976.8 994.3 998.9 1028.7 14732.3 14764.2 14836.7 15041.5 15184.2 408606.0 410656.0 412007.0 413067.0 413968.0 49386.8 49866.5 49904.0 49948.8 49972.0 1102.7 1177.3 1187.2 1218.7 1221.1 28241.2 28822.7 29000.6 29277.6 29343.0 40920.4 41144.4 41359.8 41363.0 41485.2 306520.0 307821.0 307919.0 307963.0 307999.0 21520.2 24209.0 25249.9 25255.5 25391.0 41853.6 42105.8 42345.0 43327.0 43392.8 35564.0 36158.0 36367.6 36544.5 36633.8 35977.0 36467.0 36526.3 36565.5 36605.8 35016.6 35614.8 36325.1 36357.6 36419.5 30819.4 32608.2 32694.8 32976.3 33332.3 24704.8 24880.0 24912.3 25363.6 25566.9 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | [#2] jquery.html Cycles:180 Average:25303.60 Median:48662.88 stddev:62894.75 (129.2%) stddev-sans-first:63043.33 10:57:06 INFO - PROCESS | 26592 | Values: 372.1 374.7 376.7 389.5 390.6 15648.7 17152.7 17282.7 17507.1 17580.9 16099.4 16828.5 16844.5 16845.0 17409.0 5525.0 5815.8 5861.6 5901.1 5903.8 4064.5 4308.8 6609.7 6977.0 6978.9 6961.5 6992.4 7055.8 7085.5 7089.4 6820.4 6962.6 6962.9 7012.2 7027.2 6172.5 6529.5 6532.9 6535.2 6539.0 22280.6 22496.9 23002.0 23217.8 23232.9 1980.2 2155.8 2194.9 2361.5 2397.0 14072.3 14084.4 14119.4 14144.4 14640.0 1823.5 1842.3 1954.1 1979.3 2018.2 79715.2 83256.6 83372.0 83494.6 83890.0 16992.7 17065.7 17100.7 17136.1 17257.6 29373.6 29417.4 29717.9 29759.9 29799.7 7882.3 8262.4 8282.0 8289.6 8381.2 6662.3 6669.8 6755.2 6762.4 6814.7 7193.0 8078.2 8428.8 8536.4 8639.2 2896.6 3110.8 3280.4 3317.5 3454.3 773.8 854.9 861.8 871.1 871.5 16118.6 16728.4 17082.1 17089.3 17183.9 843.7 870.4 888.0 893.8 900.9 9315.7 9585.2 9596.4 9636.5 9716.0 353846.0 385029.0 385876.0 386274.0 388132.0 74278.6 76224.6 76354.6 76391.2 76412.6 915.7 916.3 929.6 941.6 944.4 14881.7 14939.9 15011.3 16292.5 16329.8 29201.6 33500.8 36067.5 36316.8 36936.0 47060.8 47755.3 47859.0 48560.0 48765.8 7613.6 7685.7 8014.6 8014.8 8385.2 7747.0 8656.4 11524.3 11708.2 11988.5 9988.6 10211.4 10352.7 10448.3 10459.8 4707.7 8476.0 9357.0 10390.7 10798.4 9808.3 10226.4 10334.0 10472.6 10682.0 7334.3 7457.1 7527.5 7622.9 7652.2 9323.3 9722.4 9917.9 9932.0 10107.4 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | [#3] mootools.html Cycles:180 Average:4957.89 Median:5388.74 stddev:2698.16 (50.1%) stddev-sans-first:2691.01 10:57:06 INFO - PROCESS | 26592 | Values: 1208.2 1361.8 1375.3 1392.5 1431.0 6137.4 7308.9 7568.3 7568.8 7728.2 7435.4 7580.4 7587.7 7588.8 7594.9 2250.0 2279.9 2282.4 2282.6 2287.1 2205.8 2234.7 2259.1 2259.7 2260.3 2222.2 2226.9 2232.4 2232.6 2255.0 2204.8 2219.8 2222.0 2226.9 2229.1 2214.6 2218.4 2221.2 2222.6 2224.4 7248.0 7465.9 7564.6 7606.4 7610.2 4307.6 4416.5 4418.8 4421.4 4434.4 2794.2 2867.6 2908.9 2916.1 2916.9 924.3 952.6 960.8 961.5 967.5 9990.9 10045.7 10110.7 10121.0 10176.5 4020.7 4123.6 4185.8 4196.6 4196.9 8058.4 8106.7 8110.5 8119.2 8121.6 4313.9 4334.9 4369.3 4374.1 4389.5 3389.9 3562.4 3859.8 3861.0 3982.8 3461.1 3470.8 3552.0 3578.0 3587.1 1059.8 1062.7 1066.1 1066.7 1070.7 661.9 725.5 729.6 813.2 855.7 4869.2 5108.3 5183.3 5190.7 5267.9 825.6 831.3 834.5 867.2 867.5 3592.4 3661.9 3664.2 3719.1 3725.7 8943.2 9213.9 9301.2 9305.7 9311.6 9164.9 9166.8 9195.3 9206.7 9213.6 6635.1 6978.4 6997.4 7115.4 7116.9 5090.0 5336.7 5440.8 5597.6 5704.3 8148.0 8441.7 8541.7 8598.0 8609.5 8484.5 8685.8 8697.6 8708.3 8726.9 6402.5 6456.6 6522.9 6525.0 6526.1 6936.8 7012.9 7076.4 7079.2 7084.6 6262.5 6380.5 6431.1 6493.9 6654.9 6333.0 6344.5 6447.7 6647.0 6682.3 5481.8 5954.1 6080.0 6138.5 6138.6 4729.8 4763.5 4771.2 4793.5 4879.5 4764.9 4788.6 4800.6 4849.2 4909.9 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | [#4] prototype.html Cycles:180 Average:4127.36 Median:4745.88 stddev:1183.58 (24.9%) stddev-sans-first:1151.63 10:57:06 INFO - PROCESS | 26592 | Values: 306.2 307.1 312.4 317.7 318.0 4753.4 4773.2 4783.0 4788.5 4804.1 4691.5 4741.7 4755.2 4761.2 4763.9 3561.4 3628.6 3682.2 3715.1 3768.6 3715.4 3736.8 3738.9 3748.9 3753.2 3712.4 3720.5 3732.6 3743.7 3746.7 3536.3 3600.3 3717.4 3740.7 3741.1 3297.6 3304.9 3309.2 3313.6 3326.4 4505.4 4548.2 4553.5 4597.2 4618.4 4156.2 4195.8 4268.9 4275.6 4282.4 4893.0 4903.7 4933.4 4934.6 4935.6 3581.6 3585.0 3611.9 3619.3 3620.8 4986.3 5152.7 5175.5 5181.3 5211.0 3673.8 3698.2 3703.4 3706.4 3716.6 3743.6 3771.3 3780.5 3789.8 3793.6 4244.1 4257.7 4259.2 4272.2 4277.4 4794.4 4940.7 4952.4 4954.2 4987.5 1264.2 1278.9 1280.0 1282.7 1286.0 844.7 848.8 850.3 860.2 880.4 4333.5 4342.2 4348.9 4362.0 4368.8 5050.9 5052.8 5064.1 5069.6 5076.9 4239.1 4332.0 4339.8 4355.7 4365.7 2579.9 2687.9 2696.7 2698.7 2705.5 4723.2 4738.0 4750.1 4784.8 4795.7 4893.5 5147.5 5165.5 5167.0 5186.2 4741.1 4772.2 4775.9 4801.4 4802.2 5019.8 5031.9 5049.3 5060.5 5078.7 4781.4 4906.0 4935.7 4943.9 4961.2 4845.6 4859.6 4874.5 4888.3 4909.6 3644.9 3656.3 3666.3 3695.9 3704.8 5114.8 5225.5 5233.9 5258.6 5260.7 4967.6 4968.2 4974.2 4982.6 5000.4 4978.6 4997.4 5016.2 5018.6 5052.2 4701.8 4893.3 4905.5 4909.0 4917.1 4752.0 4855.6 4864.8 4875.2 4978.6 4802.7 4814.7 4842.7 4911.0 4933.3 10:57:06 INFO - PROCESS | 26592 | 10:57:06 INFO - PROCESS | 26592 | [#5] yui.html Cycles:180 Average:16808.79 Median:26141.11 stddev:23799.50 (91.0%) stddev-sans-first:23835.38 10:57:06 INFO - PROCESS | 26592 | Values: 660.8 687.7 698.5 702.2 750.5 12277.9 13107.1 13127.7 13187.7 13198.1 12188.6 12304.9 12313.2 12322.0 12325.9 617.3 749.7 769.6 780.9 782.1 779.3 782.3 785.1 785.3 785.7 834.1 834.5 834.9 834.9 835.7 646.5 797.5 824.9 826.1 828.6 607.8 608.0 608.2 608.4 608.5 11481.7 11507.1 11507.7 11515.9 11905.2 12100.0 12573.9 12591.1 12725.7 12954.1 10555.3 10566.5 10567.8 10772.4 10928.8 4093.9 4134.3 4138.9 4171.4 4259.5 56747.7 57155.7 57246.7 59512.0 59543.4 10190.3 10406.9 10453.2 10528.9 10536.4 18956.3 18998.0 19008.4 19054.0 19079.4 6160.1 6268.6 6269.5 6270.6 6447.4 3610.2 3682.4 3718.6 3725.9 3734.1 2660.6 2740.2 2741.1 2750.6 2753.9 514.2 545.1 553.0 556.1 559.2 525.7 557.5 605.1 623.9 647.7 8550.2 8666.7 8684.1 8729.3 8736.7 514.3 568.8 575.1 597.2 610.5 4159.7 4298.2 4353.2 4373.4 4387.9 57515.0 91401.0 94781.1 101130.5 101252.5 51756.5 55284.4 81749.2 82504.6 88248.1 46071.8 54480.5 75229.2 76464.0 76877.2 8118.4 8267.1 8283.5 8333.0 8367.6 45005.3 46017.3 47559.3 47684.8 48318.5 64332.7 67617.0 68280.3 68298.7 68944.3 10107.8 10165.6 10332.2 10409.3 10601.9 11356.9 11655.5 11975.8 12066.8 12176.1 10687.0 10717.9 11180.5 11193.6 11195.1 10390.0 10689.4 10812.4 10814.1 10836.7 10310.5 10530.1 10822.6 11106.9 11215.3 7997.5 8114.1 8226.4 8335.8 8386.0 8839.0 8861.9 9018.9 9056.9 9117.1 10:57:06 INFO - PROCESS | 26592 | -------- Summary: end -------- 10:57:06 INFO - PROCESS | 26592 | 10:57:07 INFO - TEST-INFO | 26592: exit 0 10:57:07 INFO - TEST-OK | dromaeo_css | took 1120449ms 10:57:07 INFO - TEST-START | kraken 10:57:07 INFO - operating with platform_type : linux_ 10:57:07 INFO - Initialising browser for kraken test... 10:57:07 INFO - TEST-INFO | started process 26767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuURez7/profile http://localhost:32930/getInfo.html) 10:57:12 INFO - PROCESS | 26767 | __metrics Screen width/height:1600/1200 10:57:12 INFO - PROCESS | 26767 | colorDepth:24 10:57:12 INFO - PROCESS | 26767 | Browser inner width/height: 1024/697 10:57:12 INFO - PROCESS | 26767 | __metrics 10:57:13 ERROR - PROCESS | 26767 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:57:14 INFO - TEST-INFO | 26767: exit 0 10:57:14 INFO - Browser initialized. 10:57:14 INFO - Running cycle 1/1 for kraken test... 10:57:14 INFO - TEST-INFO | started process 26850 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuURez7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:57:26 INFO - PROCESS | 26850 | RSS: Main: 157876224 10:57:26 INFO - PROCESS | 26850 | 10:57:29 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32930/tests/kraken/driver.html?testName=audio-beat-detection) 10:57:29 INFO - PROCESS | 26850 | RSS: Main: 214704128 10:57:29 INFO - PROCESS | 26850 | 10:57:32 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32930/tests/kraken/driver.html?testName=audio-dft) 10:57:33 INFO - PROCESS | 26850 | RSS: Main: 204750848 10:57:33 INFO - PROCESS | 26850 | 10:57:36 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32930/tests/kraken/driver.html?testName=audio-fft) 10:57:37 INFO - PROCESS | 26850 | RSS: Main: 271253504 10:57:37 INFO - PROCESS | 26850 | 10:57:39 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32930/tests/kraken/driver.html?testName=audio-oscillator) 10:57:39 INFO - PROCESS | 26850 | RSS: Main: 180568064 10:57:39 INFO - PROCESS | 26850 | 10:57:42 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32930/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:57:42 INFO - PROCESS | 26850 | RSS: Main: 184971264 10:57:42 INFO - PROCESS | 26850 | 10:57:45 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32930/tests/kraken/driver.html?testName=imaging-darkroom) 10:57:46 INFO - PROCESS | 26850 | RSS: Main: 221470720 10:57:46 INFO - PROCESS | 26850 | 10:57:50 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32930/tests/kraken/driver.html?testName=imaging-desaturate) 10:57:50 INFO - PROCESS | 26850 | RSS: Main: 221970432 10:57:50 INFO - PROCESS | 26850 | 10:57:53 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32930/tests/kraken/driver.html?testName=json-parse-financial) 10:57:54 INFO - PROCESS | 26850 | RSS: Main: 221208576 10:57:54 INFO - PROCESS | 26850 | 10:57:55 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32930/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:57:56 INFO - PROCESS | 26850 | RSS: Main: 204312576 10:57:56 INFO - PROCESS | 26850 | 10:57:58 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:57:59 INFO - PROCESS | 26850 | RSS: Main: 210227200 10:57:59 INFO - PROCESS | 26850 | 10:58:01 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:58:02 INFO - PROCESS | 26850 | RSS: Main: 219299840 10:58:02 INFO - PROCESS | 26850 | 10:58:04 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:58:05 INFO - PROCESS | 26850 | RSS: Main: 223707136 10:58:05 INFO - PROCESS | 26850 | 10:58:08 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:58:08 INFO - PROCESS | 26850 | RSS: Main: 222806016 10:58:09 INFO - PROCESS | 26850 | 10:58:10 INFO - PROCESS | 26850 | Cycle 1(1): loaded http://localhost:32930/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32930/tests/kraken/driver.html?testName=ai-astar) 10:58:11 INFO - PROCESS | 26850 | RSS: Main: 265281536 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | __start_tp_report 10:58:11 INFO - PROCESS | 26850 | _x_x_mozilla_page_load 10:58:11 INFO - PROCESS | 26850 | _x_x_mozilla_page_load_details 10:58:11 INFO - PROCESS | 26850 | |i|pagename|runs| 10:58:11 INFO - PROCESS | 26850 | |0;ai-astar;112;108;113;115;113;115;113;114;112;113 10:58:11 INFO - PROCESS | 26850 | |1;audio-beat-detection;157;130;131;177;132;133;152;129;182;133 10:58:11 INFO - PROCESS | 26850 | |2;audio-dft;162;160;160;161;161;161;162;161;161;161 10:58:11 INFO - PROCESS | 26850 | |3;audio-fft;75;75;76;116;76;77;74;75;108;76 10:58:11 INFO - PROCESS | 26850 | |4;audio-oscillator;86;87;113;86;88;87;117;86;86;128 10:58:11 INFO - PROCESS | 26850 | |5;imaging-gaussian-blur;118;117;117;117;117;116;117;119;117;117 10:58:11 INFO - PROCESS | 26850 | |6;imaging-darkroom;147;148;147;147;149;149;148;150;147;147 10:58:11 INFO - PROCESS | 26850 | |7;imaging-desaturate;95;94;95;94;94;95;96;95;95;95 10:58:11 INFO - PROCESS | 26850 | |8;json-parse-financial;64;63;63;63;63;67;64;64;62;63 10:58:11 INFO - PROCESS | 26850 | |9;json-stringify-tinderbox;67;67;67;66;65;67;66;66;66;67 10:58:11 INFO - PROCESS | 26850 | |10;stanford-crypto-aes;85;85;84;86;87;90;85;86;113;86 10:58:11 INFO - PROCESS | 26850 | |11;stanford-crypto-ccm;134;138;134;133;140;142;136;136;139;149 10:58:11 INFO - PROCESS | 26850 | |12;stanford-crypto-pbkdf2;174;168;168;170;168;168;165;170;166;165 10:58:11 INFO - PROCESS | 26850 | |13;stanford-crypto-sha256-iterative;67;68;77;67;67;68;67;67;67;67 10:58:11 INFO - PROCESS | 26850 | __end_tp_report 10:58:11 INFO - PROCESS | 26850 | __start_cc_report 10:58:11 INFO - PROCESS | 26850 | _x_x_mozilla_cycle_collect,1913 10:58:11 INFO - PROCESS | 26850 | __end_cc_report 10:58:11 INFO - PROCESS | 26850 | __startTimestamp1464976691027__endTimestamp 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | ------- Summary: start ------- 10:58:11 INFO - PROCESS | 26850 | Number of tests: 14 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.99 (1.8%) stddev-sans-first:2.09 10:58:11 INFO - PROCESS | 26850 | Values: 112.0 108.0 113.0 115.0 113.0 115.0 113.0 114.0 112.0 113.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:142.50 stddev:20.32 (14.3%) stddev-sans-first:21.13 10:58:11 INFO - PROCESS | 26850 | Values: 157.0 130.0 131.0 177.0 132.0 133.0 152.0 129.0 182.0 133.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.60 10:58:11 INFO - PROCESS | 26850 | Values: 162.0 160.0 160.0 161.0 161.0 161.0 162.0 161.0 161.0 161.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.50 stddev:15.53 (20.6%) stddev-sans-first:16.21 10:58:11 INFO - PROCESS | 26850 | Values: 75.0 75.0 76.0 116.0 76.0 77.0 74.0 75.0 108.0 76.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:86.00 stddev:16.26 (18.9%) stddev-sans-first:16.80 10:58:11 INFO - PROCESS | 26850 | Values: 86.0 87.0 113.0 86.0 88.0 87.0 117.0 86.0 86.0 128.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 10:58:11 INFO - PROCESS | 26850 | Values: 118.0 117.0 117.0 117.0 117.0 116.0 117.0 119.0 117.0 117.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 10:58:11 INFO - PROCESS | 26850 | Values: 147.0 148.0 147.0 147.0 149.0 149.0 148.0 150.0 147.0 147.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:58:11 INFO - PROCESS | 26850 | Values: 95.0 94.0 95.0 94.0 94.0 95.0 96.0 95.0 95.0 95.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.50 stddev:1.35 (2.1%) stddev-sans-first:1.42 10:58:11 INFO - PROCESS | 26850 | Values: 64.0 63.0 63.0 63.0 63.0 67.0 64.0 64.0 62.0 63.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 10:58:11 INFO - PROCESS | 26850 | Values: 67.0 67.0 67.0 66.0 65.0 67.0 66.0 66.0 66.0 67.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:86.00 stddev:8.69 (10.1%) stddev-sans-first:9.12 10:58:11 INFO - PROCESS | 26850 | Values: 85.0 85.0 84.0 86.0 87.0 90.0 85.0 86.0 113.0 86.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:138.50 stddev:4.79 (3.5%) stddev-sans-first:4.85 10:58:11 INFO - PROCESS | 26850 | Values: 134.0 138.0 134.0 133.0 140.0 142.0 136.0 136.0 139.0 149.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:168.00 stddev:2.70 (1.6%) stddev-sans-first:1.88 10:58:11 INFO - PROCESS | 26850 | Values: 174.0 168.0 168.0 170.0 168.0 168.0 165.0 170.0 166.0 165.0 10:58:11 INFO - PROCESS | 26850 | 10:58:11 INFO - PROCESS | 26850 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:67.00 stddev:3.12 (4.7%) stddev-sans-first:3.28 10:58:11 INFO - PROCESS | 26850 | Values: 67.0 68.0 77.0 67.0 67.0 68.0 67.0 67.0 67.0 67.0 10:58:11 INFO - PROCESS | 26850 | -------- Summary: end -------- 10:58:11 INFO - PROCESS | 26850 | 10:58:12 INFO - TEST-INFO | 26850: exit 0 10:58:12 INFO - TEST-OK | kraken | took 64980ms 10:58:12 INFO - SUITE-END | took 1185s 10:58:12 INFO - Completed test suite (00:19:45) 10:58:12 INFO - javascript benchmark 10:58:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2676.4379480309135, 2677.8661066946647, 2716.157991022783, 2727.3454370950976, 2735.49393362228, 4585.082983403316, 4636.443626764784, 4701.27130294804, 4768.546988036135, 4845.491223421543, 4680.110778952006, 4743.5256474352545, 4751.477337492882, 4760.500147484516, 4777.283407488879, 4312.374705667677, 4341.2836881914445, 4411.448568928884, 4444.577765112309, 4508.188526065307, 4456.799444025027, 4466.955330446686, 4469.396631454761, 4500.21246281901, 4522.819087236507, 4209.095044565433, 4237.385579091046, 4320.589543993316, 4347.84782532613, 4389.275769498045, 4263.339182426729, 4270.722403043823, 4280.058387154852, 4298.4197133387215, 4329.328954012133, 4320.0, 4323.2434323993175, 4324.654027677778, 4364.978175109136, 4373.562643735633, 4497.055618320124, 4547.18150732868, 4610.700304480198, 4636.953630463686, 4650.860474185746, 5119.206522988943, 5128.384593848725, 5141.203113517411, 5146.948530514722, 5156.226566015128, 5147.588192944556, 5154.92267615986, 5156.458571849977, 5162.406323272797, 5168.638195326329, 4589.793459294307, 4643.698159619614, 4692.95307046932, 4696.178168820443, 4722.645801564897, 5115.18157094863, 5143.27994080829, 5185.118529849935, 5196.142636465017, 5236.73816309183, 4792.233242681224, 4963.726995015309, 5011.674241174312, 5014.924776128361, 5019.623528235397, 4975.154223781966, 4993.90012199754, 5011.348524691767, 5040.974795126001, 5088.287639730441, 5023.321851550067, 5031.698098114125, 5034.697918124924, 5036.64743467961, 5084.466131056186, 4994.575461085776, 4998.375203099613, 5003.656479929147, 5005.799768009311, 5014.89970200594, 4168.937465938014, 4209.494860616745, 4214.704970652025, 4264.189803937304, 4307.56924307573, 3802.1825307558456, 3833.1758671885677, 3851.8651847185215, 3895.474110995036, 3905.414187871785, 4345.891352716208, 4347.434833471629, 4367.912641747147, 4384.342348647665, 4387.3857659927635, 4868.853934381974, 4888.266759986031, 5150.0472412603785, 5150.871228219325, 5202.531772140525, 4284.721493102938, 4328.329108988112, 4344.261475549164, 4346.6088052075465, 4355.194289056535, 4539.001419687664, 4574.6340292776495, 4577.1074640444795, 4624.44506659203, 4627.976860115745, 4589.357489951476, 4705.882352941204, 4762.571368576775, 4801.7118972861745, 4805.687630304019, 5034.270030845581, 5082.898342033065, 5110.361204849245, 5114.0, 5156.56169225605, 4777.044591081728, 4787.904241915073, 4791.377120974251, 4797.9040419192115, 4837.588804951619, 4816.686915350491, 4922.532359425849, 4962.181240095344, 5004.749762511787, 5053.797848086036, 5044.167712327425, 5055.191169412877, 5063.3417655704525, 5066.36670416198, 5092.694438333712, 4923.876903077452, 4925.778339974638, 4927.507249275044, 4935.62982776286, 4962.280469331999, 4758.4765675776325, 4848.80604775805, 4891.584215341736, 4921.581665558468, 4951.0, 4835.105505481497, 4841.298011788342, 4890.510948905081, 4891.095147397743, 4891.755412229303, 4956.430010548689, 4956.504349565014, 4966.528179822911, 4984.42679091909, 5005.949940500694, 4724.834630787906, 4802.111609352281, 4834.516548345137, 4846.079244943449, 4879.463259041431], "value": 4645.7329727627, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.4895624678433, 594.3224723814852, 595.2202614574907, 599.0325624117047, 614.5237440983238, 27567.172984810462, 40105.59894401055, 40923.59076409239, 40951.180976380456, 40975.18049639013, 41472.79263603678, 41954.790226048826, 42002.0, 42045.57954420455, 42080.57919420805, 7661.042369703787, 7791.649375778075, 7799.142094369643, 7799.454038217327, 7800.18098099697, 7841.490303130278, 7984.560849153294, 7989.400794940403, 7990.320822730075, 7991.081025682034, 7942.642581083836, 8019.318357939582, 8022.759317220463, 8024.879626805603, 8028.518288902685, 7907.16974717655, 7964.084130325029, 7973.960130199312, 7974.681012759483, 7976.999999999971, 7833.804154896173, 7926.920730792734, 7929.563873986928, 7930.841383172362, 7932.881006784903, 37830.24339513194, 38208.61791382078, 38283.425748613794, 38310.0, 38334.61665383366, 3039.4832878410502, 3141.2618034761813, 3182.904512864595, 3189.5375170600364, 3241.546183534307, 29217.561736573967, 29623.259418514495, 29770.85114574435, 29981.400371992437, 30179.698203018128, 2892.4359749848786, 2900.9709902900913, 2914.606528118698, 2959.689232630586, 3022.6523949745842, 276747.0, 280760.0, 280786.0, 280814.5959270211, 281301.0, 32101.0, 57082.0, 57578.4242157573, 57590.7120464395, 57592.39260859121, 32764.836175818968, 34444.827775861464, 34455.65544344574, 34463.13842153966, 34472.31055378878, 13910.86089139116, 14056.578302650936, 14066.718665626835, 14070.929645351707, 14075.859241407456, 11640.65078047643, 11751.118666100077, 11762.0, 11762.35307058103, 11785.410729463492, 7978.441509094308, 7989.440739148204, 8000.479968802009, 8061.1938806118915, 8080.959595202104, 3049.2224482756756, 3064.785465017472, 3068.647105582842, 3091.7990330628436, 3145.811251324928, 933.3419938943028, 935.9625614975324, 959.5586030425943, 1003.7540802503433, 1018.2719355660738, 27141.728582714313, 27745.861270693516, 27929.92770795211, 28322.942892538915, 28490.0, 931.3340961212765, 976.8046390721742, 994.3288280410694, 998.8651532043082, 1028.7115784665727, 14732.337044832793, 14764.187969661556, 14836.700311451743, 15041.548753537412, 15184.240787960558, 408606.0, 410656.0, 412007.0, 413067.0, 413968.0, 49386.75306623516, 49866.50133498691, 49904.0, 49948.75025624849, 49972.0, 1102.685734565645, 1177.264209868832, 1187.186777057701, 1218.6770505815966, 1221.1451983611507, 28241.15276541707, 28822.685391180283, 29000.564991524723, 29277.560836587894, 29343.0, 40920.38619420651, 41144.38283425691, 41359.793201033805, 41363.0, 41485.1702965945, 306520.0, 307821.0, 307919.0, 307963.0, 307999.0, 21520.246791362228, 24209.0, 25249.87375063113, 25255.494890101727, 25391.0, 41853.58146418497, 42105.78947105367, 42345.0, 43327.0, 43392.78303608462, 35564.0, 36158.0, 36367.63632363748, 36544.45183322306, 36633.81683091674, 35977.0, 36467.0, 36526.26947461089, 36565.45151822778, 36605.81697091497, 35016.64983350134, 35614.8219258902, 36325.09187270339, 36357.63642363543, 36419.45370819493, 30819.383612328078, 32608.184795380304, 32694.83652581817, 32976.34047318992, 33332.333353333284, 24704.752952470244, 24880.0, 24912.252632421056, 25363.61954570646, 25566.872165639797], "value": 16632.019777249978, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.11808015004436, 374.65157403614626, 376.67229510326, 389.52867030892617, 390.5898806253435, 15648.687026259468, 17152.742708859376, 17282.740758888594, 17507.124643767824, 17580.912095439537, 16099.356025758956, 16828.495145145636, 16844.494665160033, 16845.0, 17409.04250266235, 5524.99999999999, 5815.825525234229, 5861.589688721791, 5901.144334071557, 5903.763849446017, 4064.512258528966, 4308.784560771949, 6609.735610575572, 6977.0, 6978.895316570255, 6961.512694111414, 6992.405645520112, 7055.753048643299, 7085.539439936413, 7089.432845372384, 6820.35206655367, 6962.617056061915, 6962.860742785116, 7012.193597736249, 7027.227005029468, 6172.537059720494, 6529.510286728514, 6532.934670653281, 6535.183102112236, 6539.0, 22280.554388912293, 22496.887515562317, 23002.0, 23217.767822321897, 23232.883835580884, 1980.2079168332639, 2155.8275337972923, 2194.9341519754435, 2361.480474295652, 2396.9640455393182, 14072.2963851807, 14084.43662253519, 14119.364628591738, 14144.36350364236, 14640.033914366204, 1823.5167680564662, 1842.2630947620926, 1954.1108795498014, 1979.257778333125, 2018.1927229108328, 79715.20284797136, 83256.58371708164, 83372.0, 83494.58252708698, 83890.0, 16992.74510882338, 17065.7440138398, 17100.74348884768, 17136.143192840307, 17257.56856078608, 29373.559396609067, 29417.411651766844, 29717.85141074281, 29759.85120074386, 29799.72203057662, 7882.330001949898, 8262.42163048581, 8281.95859020701, 8289.626966786509, 8381.245687888118, 6662.267150613428, 6669.799906002828, 6755.18937727466, 6762.425193858478, 6814.693338799667, 7193.0, 8078.192180781993, 8428.831423371621, 8536.359773017048, 8639.179277968582, 2896.6089577907132, 3110.7977981431136, 3280.3603297356785, 3317.5023746437746, 3454.2573346730574, 773.833625770462, 854.8717692346198, 861.7759382560579, 871.063606622883, 871.4945331707594, 16118.597035073983, 16728.41450549225, 17082.145892705565, 17089.31642734277, 17183.914080429517, 843.6583183810567, 870.4472659861034, 888.0187392930848, 893.8167675626481, 900.8828852249165, 9315.673951411669, 9585.233181345475, 9596.424214547149, 9636.518174091267, 9715.951420242995, 353846.0, 385029.0, 385876.0, 386274.0, 388132.0, 74278.62860685663, 76224.61887690525, 76354.61822690851, 76391.23608763953, 76412.61793690996, 915.7115508614899, 916.326500022483, 929.5677509957902, 941.6233506597416, 944.414463032924, 14881.702365952404, 14939.891529829383, 15011.32449039774, 16292.51122466328, 16329.755053673967, 29201.561976569945, 33500.83249583736, 36067.45898811572, 36316.81841590775, 36936.0, 47060.7646961763, 47755.28367074427, 47859.0, 48560.0, 48765.75617121892, 7613.581253031137, 7685.692572297045, 8014.599270036359, 8014.83970320602, 8385.245327920517, 7747.0, 8656.437331573428, 11524.308541487537, 11708.238964467282, 11988.460519276829, 9988.60045598197, 10211.438370889673, 10352.741181470523, 10448.32085914413, 10459.790804183722, 4707.670463067552, 8475.957620211859, 9356.95321523388, 10390.740231494274, 10798.352098874093, 9808.264380171659, 10226.437545934747, 10334.0, 10472.581096756045, 10682.0, 7334.339909408179, 7457.067866516685, 7527.473076884566, 7622.9237707622215, 7652.1582625912215, 9323.300752443458, 9722.368046076983, 9917.900820991697, 9931.950340248253, 10107.444090575089], "value": 9176.519628590255, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1208.1784386617096, 1361.8025386318984, 1375.2848518770234, 1392.456941792701, 1430.9999999999993, 6137.355577664348, 7308.853822923545, 7568.281013303727, 7568.772936811891, 7728.188540203268, 7435.367993720541, 7580.39356851452, 7587.696492140308, 7588.772336829904, 7594.9240507594905, 2249.966250506244, 2279.9316020519327, 2282.395165281202, 2282.5891339558957, 2287.0737351372723, 2205.8014778669917, 2234.7094877665963, 2259.0963614554225, 2259.6836442898007, 2260.333201705501, 2222.1555808792637, 2226.92205772798, 2232.3749350181974, 2232.598132336179, 2255.041607316884, 2204.8015678588845, 2219.833512486562, 2221.9666704999436, 2226.877521736296, 2229.0972156276653, 2214.568159208955, 2218.3566765637947, 2221.2336743823357, 2222.588821068108, 2224.443889027743, 7248.0, 7465.888011679829, 7564.621768911533, 7606.429517786189, 7610.1628820829665, 4307.590778876001, 4416.53626369233, 4418.80115394808, 4421.358902959085, 4434.379186913835, 2794.203651959182, 2867.569864520318, 2908.941821163565, 2916.066858605247, 2916.9270768230754, 924.3206243411088, 952.5761036338832, 960.7742180587557, 961.4952150121172, 967.5404183013083, 9990.85013724802, 10045.698629041068, 10110.69667909964, 10121.0, 10176.491175441271, 4020.6783457323927, 4123.649489793401, 4185.790710464465, 4196.643285320721, 4196.853110141192, 8058.395620328499, 8106.716264930699, 8110.472819266729, 8119.228673276029, 8121.5533145677555, 4313.935290970575, 4334.9349759752995, 4369.344598310279, 4374.081442897027, 4389.539098394686, 3389.9322013560086, 3562.358775420449, 3859.845606175778, 3860.96139038606, 3982.78094704794, 3461.065512311712, 3470.7917524948584, 3551.9644803551632, 3577.982110089433, 3587.0852932502567, 1059.7668512927144, 1062.7236918401272, 1066.1151244467073, 1066.664000839733, 1070.71626019105, 661.8742438936587, 725.4957804325943, 729.6023667101442, 813.1705660226654, 855.6791203298762, 4869.220924652039, 5108.259302401206, 5183.326167598264, 5190.714510701948, 5267.894642107136, 825.6449726617492, 831.3224721851672, 834.4534330013851, 867.1545243387646, 867.5055218525506, 3592.4431713084514, 3661.8901432957055, 3664.2121943782213, 3719.0702324418894, 3725.720570957189, 8943.23982461498, 9213.861792073125, 9301.209397201179, 9305.674301399418, 9311.580978855967, 9164.90835091654, 9166.816663666823, 9195.26437884981, 9206.723798286062, 9213.585388657526, 6635.10426092481, 6978.406835418945, 6997.370236678723, 7115.395191408685, 7116.92883071173, 5089.9745501272255, 5336.67979921206, 5440.809571664972, 5597.608167428404, 5704.258446401941, 8148.0, 8441.697931289267, 8541.658333666584, 8597.957010214908, 8609.48343099391, 8484.533350665526, 8685.826283474422, 8697.608607612547, 8708.303335733253, 8726.912730872864, 6402.487800975818, 6456.612603243821, 6522.934770652233, 6524.9673751630935, 6526.118973938457, 6936.757213497705, 7012.859742805217, 7076.433885289061, 7079.22128565847, 7084.57492550428, 6262.46769024638, 6380.457661098859, 6431.067495213261, 6493.935060649522, 6654.900176497454, 6333.0, 6344.492440604833, 6447.70985305653, 6647.036179754005, 6682.331766823473, 5481.808136715196, 5954.10688396744, 6079.969600152148, 6138.508919286357, 6138.631682099089, 4729.787159577759, 4763.452202996561, 4771.23660214364, 4793.496682848251, 4879.512048795092, 4764.928526072181, 4788.569028787426, 4800.639952003544, 4849.224124140121, 4909.9263511048075], "value": 4023.942017458941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2435783614472, 307.0956034478462, 312.42981558655447, 317.6902520042958, 317.95230715392705, 4753.358296629958, 4773.164696178168, 4783.0, 4788.473267940528, 4804.063207674505, 4691.460482044566, 4741.668083234175, 4755.167845627018, 4761.214399624067, 4763.90472190556, 3561.376759067166, 3628.5827129880136, 3682.1715114099247, 3715.1269451678836, 3768.5854555998817, 3715.4241092630687, 3736.75711078779, 3738.8504459821575, 3748.868789592365, 3753.1555400035045, 3712.4060150375944, 3720.479132921393, 3732.552093748754, 3743.66307032367, 3746.662800347968, 3536.284901822614, 3600.2799440111953, 3717.4052151655874, 3740.738148329618, 3741.0834345585235, 3297.6314829345793, 3304.8843290484956, 3309.2223327518013, 3313.5692359993045, 3326.3679900818765, 4505.36924830524, 4548.226801443762, 4553.476350219701, 4597.195490789099, 4618.445786505641, 4156.168766246763, 4195.845376963591, 4268.9146217075795, 4275.572442755731, 4282.357646353072, 4892.9510704892855, 4903.730294833783, 4933.407991041098, 4934.580560652349, 4935.5804756595735, 3581.6418358164237, 3584.982075089608, 3611.855525778966, 3619.2580520993133, 3620.818959052037, 4986.252062190664, 5152.690838549699, 5175.534201921845, 5181.274621552986, 5210.951593317914, 3673.779573225615, 3698.1863989922176, 3703.407454807218, 3706.3513885070006, 3716.6283371663158, 3743.6069212732814, 3771.26460340231, 3780.4707340972286, 3789.7915614640906, 3793.563740169916, 4244.087521182961, 4257.74453532789, 4259.21204577148, 4272.16692744918, 4277.401163837066, 4794.4486384065585, 4940.654154209243, 4952.4304704959395, 4954.207326827763, 4987.501249874984, 1264.2035517623838, 1278.878506541877, 1280.020784100164, 1282.6985658370274, 1285.9807102893465, 844.7085755414372, 848.8243782361351, 850.2517784349733, 860.2214995429147, 880.3837313880309, 4333.544977777288, 4342.153280110411, 4348.913021739548, 4361.971683167342, 4368.759718215465, 5050.924236136461, 5052.823151189691, 5064.0756512525, 5069.5690866276045, 5076.923846142311, 4239.144884223633, 4331.981984233703, 4339.82640694375, 4355.651547876162, 4365.738055716667, 2579.948401031969, 2687.87904544296, 2696.7438093381097, 2698.662667166604, 2705.485957668076, 4723.220668589644, 4738.005018945991, 4750.097481478508, 4784.784684689197, 4795.712257264575, 4893.535114164149, 5147.485251474823, 5165.483451654804, 5166.9999999999245, 5186.196139598368, 4741.051789642016, 4772.164871147535, 4775.928361074656, 4801.351817504592, 4802.207635740134, 5019.824306149268, 5031.874203144951, 5049.267856160763, 5060.49395060491, 5078.704930242764, 4781.42622885256, 4905.975470122627, 4935.728534930613, 4943.8629115302965, 4961.181405068123, 4845.5881250094135, 4859.635527335393, 4874.488178741323, 4888.291197776373, 4909.607231421549, 3644.890653280406, 3656.323580137683, 3666.2667466507337, 3695.870644527429, 3704.814759261972, 5114.79540818378, 5225.529702326809, 5233.895322093461, 5258.553022992935, 5260.710660913686, 4967.577755890789, 4968.180250258667, 4974.179260422135, 4982.601391888567, 5000.449950505515, 4978.576820970258, 4997.425296090851, 5016.197408414636, 5018.648694591344, 5052.1663925451885, 4701.811927522997, 4893.314935908905, 4905.5094490550655, 4908.950910490849, 4917.065757506205, 4751.954569994596, 4855.611551075976, 4864.781084851261, 4875.219964805614, 4978.601711863114, 4802.711837289774, 4814.711117332971, 4842.709437433765, 4910.975445122752, 4933.309336692938], "value": 3756.4846870327137, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.7588230295942, 687.6767919078034, 698.4796326736579, 702.1749444402825, 750.53091817614, 12277.877221227796, 13107.082504224683, 13127.737445251114, 13187.6703082423, 13198.142120762155, 12188.634340969786, 12304.93847530761, 12313.199642023263, 12322.0, 12325.876741232585, 617.3363634093345, 749.6888791151665, 769.5844244108175, 780.9258120478545, 782.0849605961039, 779.3375630713876, 782.2568559868148, 785.1128225105623, 785.2657764989752, 785.7053604898164, 834.0866750907777, 834.4826207751203, 834.8956380452444, 834.9248567628912, 835.6699103854005, 646.5474168082337, 797.5175019113469, 824.9133840946674, 826.1078035721406, 828.5650033732278, 607.8146165419545, 608.0484042473515, 608.1546650156287, 608.4128815692877, 608.5496732418023, 11481.655550333504, 11507.07943364529, 11507.654770356903, 11515.942420287845, 11905.226160299553, 12100.0, 12573.937130314382, 12591.055670824724, 12725.681857953532, 12954.139220401592, 10555.313904596176, 10566.471676416148, 10567.78864422715, 10772.353658780497, 10928.836067458995, 4093.9385909211387, 4134.3178375567995, 4138.937915931264, 4171.374293855915, 4259.51015633203, 56747.71626141884, 57155.71422142904, 57246.713766430905, 59512.0, 59543.40456595422, 10190.337628054152, 10406.895931040744, 10453.163746900229, 10528.947355263175, 10536.367817930795, 18956.336528221447, 18998.0, 19008.42974710761, 19054.04729763534, 19079.427617171506, 6160.1375807387, 6268.62388256697, 6269.529785266123, 6270.561060725706, 6447.4197322241225, 3610.223801882555, 3682.447632855093, 3718.6467285607823, 3725.8882233533036, 3734.066483379155, 2660.5743081107316, 2740.1916434651826, 2741.08173761792, 2750.587411888233, 2753.8898444062415, 514.1901505129437, 545.1441237257546, 553.0294333444859, 556.0991194265316, 559.1556749308573, 525.7397588193792, 557.4926816596877, 605.1135087097423, 623.9438450539383, 647.6535053746318, 8550.230479256898, 8666.739997800076, 8684.131586841266, 8729.345299102593, 8736.650533978696, 514.2902794144057, 568.8236646639555, 575.0999685492192, 597.2146627185238, 610.487190759764, 4159.708820382544, 4298.161858437637, 4353.238183317907, 4373.436377597805, 4387.934180987288, 57515.0, 91401.0, 94781.0521894786, 101130.4943475278, 101252.49373753232, 51756.482435175916, 55284.44715552873, 81749.18250817535, 82504.58747706223, 88248.11751882527, 46071.76964115158, 54480.45519544833, 75229.24770752332, 76464.0, 76877.231227687, 8118.391120665856, 8267.131951145284, 8283.50298982063, 8332.958335208285, 8367.58162091905, 45005.32492012557, 46017.30974035325, 47559.286610700176, 47684.7615761919, 48318.516814831404, 64332.67833660802, 67617.0, 68280.3171968274, 68298.65850670714, 68944.31055689379, 10107.848382274418, 10165.644202452879, 10332.225083118947, 10409.27135100566, 10601.893981060091, 11356.886431135583, 11655.53377864876, 11975.820362694742, 12066.818997714865, 12176.086793490347, 10687.038166564735, 10717.89282107169, 11180.496877640688, 11193.552257909592, 11195.10439164881, 10390.0, 10689.358638481715, 10812.405317707287, 10814.080803131821, 10836.729081773019, 10310.484475776031, 10530.05229529346, 10822.56709731602, 11106.944465277622, 11215.327080375204, 7997.462381792765, 8114.148014458633, 8226.424150309422, 8335.833283334421, 8386.0, 8839.0, 8861.867071994055, 9018.909810901807, 9056.864147037932, 9117.133872282122], "value": 6404.695998985732, "unit": "score"}], "name": "dromaeo_css", "value": 6398.969225530535, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 113.0, 115.0, 113.0, 115.0, 113.0, 114.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 130.0, 131.0, 177.0, 132.0, 133.0, 152.0, 129.0, 182.0, 133.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 116.0, 76.0, 77.0, 74.0, 75.0, 108.0, 76.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 113.0, 86.0, 88.0, 87.0, 117.0, 86.0, 86.0, 128.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 119.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 149.0, 149.0, 148.0, 150.0, 147.0, 147.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 63.0, 67.0, 64.0, 64.0, 62.0, 63.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 67.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 86.0, 87.0, 90.0, 85.0, 86.0, 113.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 134.0, 133.0, 140.0, 142.0, 136.0, 136.0, 139.0, 149.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 168.0, 168.0, 170.0, 168.0, 168.0, 165.0, 170.0, 166.0, 165.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 77.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1551.7, "alertThreshold": 2.0}]} 10:58:12 INFO - Return code: 0 10:58:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:58:12 ERROR - # TBPL SUCCESS # 10:58:12 INFO - Running post-action listener: _resource_record_post_action 10:58:12 INFO - ##### 10:58:12 INFO - ##### Finished run-tests step (success) 10:58:12 INFO - ##### 10:58:12 INFO - Running post-run listener: _resource_record_post_run 10:58:12 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 533155840; Read time: 16; Write time: 1149012 10:58:12 INFO - TinderboxPrint: CPU usage
13.2% 10:58:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 10:58:12 INFO - TinderboxPrint: I/O write bytes / time
533,155,840 / 1,149,012 10:58:12 INFO - TinderboxPrint: CPU idle
8,338.1 (86.8%) 10:58:12 INFO - TinderboxPrint: CPU user
1,212.7 (12.6%) 10:58:12 INFO - TinderboxPrint: Swap in / out
0 / 0 10:58:12 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 192753664; Read time: 0; Write time: 547980 10:58:12 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295546880; Read time: 16; Write time: 567804 10:58:13 INFO - Running post-run listener: _upload_blobber_files 10:58:13 INFO - Blob upload gear active. 10:58:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:58:13 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 10:58:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:58:13 INFO - (blobuploader) - INFO - Open directory for files ... 10:58:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:58:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:58:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:18 INFO - (blobuploader) - INFO - Done attempting. 10:58:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:58:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:58:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:19 INFO - (blobuploader) - INFO - Done attempting. 10:58:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:58:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:58:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:19 INFO - (blobuploader) - INFO - Done attempting. 10:58:19 INFO - (blobuploader) - INFO - Iteration through files over. 10:58:19 INFO - Return code: 0 10:58:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:58:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:58:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/a4637e8d6706a15e98914404dfe2ff4879de386e7fe1423eac67cb48b08818e56c71e963916bf5ba36ac84ea7a81974e05dc01ade50f14cd0dada69553c54b9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0131897e5110874fc291ca2cab1cd035fe98d6797d6e17b4163e7cb5ecef72ace7f6b91e09d21bb2b8d8c3f3e20608d761c1720d0bdb76462397e14bf1485267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/bee6d5fff9a41616553f4ef0f959dca3acee0c08161dd6a996a1d5f210b21ef3abeb5aec4dacadb6fd8bb4481f25f164f69c9fe9e7d35780d73225da1f718780"} 10:58:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:58:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:58:19 INFO - Contents: 10:58:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/a4637e8d6706a15e98914404dfe2ff4879de386e7fe1423eac67cb48b08818e56c71e963916bf5ba36ac84ea7a81974e05dc01ade50f14cd0dada69553c54b9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0131897e5110874fc291ca2cab1cd035fe98d6797d6e17b4163e7cb5ecef72ace7f6b91e09d21bb2b8d8c3f3e20608d761c1720d0bdb76462397e14bf1485267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/bee6d5fff9a41616553f4ef0f959dca3acee0c08161dd6a996a1d5f210b21ef3abeb5aec4dacadb6fd8bb4481f25f164f69c9fe9e7d35780d73225da1f718780"} 10:58:19 INFO - Running post-run listener: copy_logs_to_upload_dir 10:58:19 INFO - Copying logs to upload dir... 10:58:19 INFO - mkdir: /builds/slave/test/build/upload/logs 10:58:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.372084 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-06-03 10:58:19.747071) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 10:58:19.751192) ========= 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=af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/a4637e8d6706a15e98914404dfe2ff4879de386e7fe1423eac67cb48b08818e56c71e963916bf5ba36ac84ea7a81974e05dc01ade50f14cd0dada69553c54b9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0131897e5110874fc291ca2cab1cd035fe98d6797d6e17b4163e7cb5ecef72ace7f6b91e09d21bb2b8d8c3f3e20608d761c1720d0bdb76462397e14bf1485267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/bee6d5fff9a41616553f4ef0f959dca3acee0c08161dd6a996a1d5f210b21ef3abeb5aec4dacadb6fd8bb4481f25f164f69c9fe9e7d35780d73225da1f718780"} build_url:https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011592 build_url: 'https://queue.taskcluster.net/v1/task/C4nGUP2_Q7Cf_oBIA-F6hQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/a4637e8d6706a15e98914404dfe2ff4879de386e7fe1423eac67cb48b08818e56c71e963916bf5ba36ac84ea7a81974e05dc01ade50f14cd0dada69553c54b9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/0131897e5110874fc291ca2cab1cd035fe98d6797d6e17b4163e7cb5ecef72ace7f6b91e09d21bb2b8d8c3f3e20608d761c1720d0bdb76462397e14bf1485267", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/bee6d5fff9a41616553f4ef0f959dca3acee0c08161dd6a996a1d5f210b21ef3abeb5aec4dacadb6fd8bb4481f25f164f69c9fe9e7d35780d73225da1f718780"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 10:58:19.777098) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:58:19.777441) ========= 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=af724c981e2a0f424dbabf99000001e7-1464971892.177083-123821398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004589 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 10:58:19.861726) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 10:58:19.862047) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 10:58:19.862335) ========= ========= Total master_lag: 0.18 =========