builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1467066750.99 results: success (0) buildid: 20160627140517 builduid: c7c5f701e90347e6b023a3355f5c744b revision: d17c6eee724effadd6138dd1ced77bd7beaa4f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:30.988016) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:30.988436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:30.988715) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.006552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.006882) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 15:32:31-- 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% 46.8M=0s 2016-06-27 15:32:31 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.070730 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.091597) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.091924) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015266 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.141533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.141941) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d17c6eee724effadd6138dd1ced77bd7beaa4f9c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d17c6eee724effadd6138dd1ced77bd7beaa4f9c --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=c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 15:32:31,394 truncating revision to first 12 chars 2016-06-27 15:32:31,394 Setting DEBUG logging. 2016-06-27 15:32:31,394 attempt 1/10 2016-06-27 15:32:31,394 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d17c6eee724e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 15:32:31,685 unpacking tar archive at: mozilla-central-d17c6eee724e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649116 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.804905) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.805210) ========= script_repo_revision: d17c6eee724effadd6138dd1ced77bd7beaa4f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.805624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.805918) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 15:32:31.818240) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-27 15:32:31.818577) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:32:32 INFO - MultiFileLogger online at 20160627 15:32:32 in /builds/slave/test 15:32:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:32:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:32:32 INFO - {'append_to_log': False, 15:32:32 INFO - 'base_work_dir': '/builds/slave/test', 15:32:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:32:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:32:32 INFO - 'branch': 'Firefox-Non-PGO', 15:32:32 INFO - 'buildbot_json_path': 'buildprops.json', 15:32:32 INFO - 'config_files': ('talos/linux_config.py',), 15:32:32 INFO - 'default_actions': ('clobber', 15:32:32 INFO - 'read-buildbot-config', 15:32:32 INFO - 'download-and-extract', 15:32:32 INFO - 'populate-webroot', 15:32:32 INFO - 'create-virtualenv', 15:32:32 INFO - 'install', 15:32:32 INFO - 'run-tests'), 15:32:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:32:32 INFO - 'download_minidump_stackwalk': True, 15:32:32 INFO - 'download_symbols': 'ondemand', 15:32:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:32:32 INFO - 'tooltool.py': '/tools/tooltool.py', 15:32:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:32:32 INFO - '/tools/misc-python/virtualenv.py')}, 15:32:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:32:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:32:32 INFO - 'installer_path': 'installer.exe', 15:32:32 INFO - 'log_level': 'info', 15:32:32 INFO - 'log_name': 'talos', 15:32:32 INFO - 'log_to_console': True, 15:32:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:32:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:32:32 INFO - 'opt_config_files': (), 15:32:32 INFO - 'pip_index': False, 15:32:32 INFO - 'sps_profile': False, 15:32:32 INFO - 'sps_profile_interval': 0, 15:32:32 INFO - 'suite': 'dromaeojs-e10s', 15:32:32 INFO - 'system_bits': '32', 15:32:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:32:32 INFO - 'title': 'talos-linux64-ix-004', 15:32:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:32:32 INFO - 'use_talos_json': True, 15:32:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:32:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:32:32 INFO - 'work_dir': 'build'} 15:32:32 INFO - ##### 15:32:32 INFO - ##### Running clobber step. 15:32:32 INFO - ##### 15:32:32 INFO - Running pre-action listener: _resource_record_pre_action 15:32:32 INFO - Running main action method: clobber 15:32:32 INFO - rmtree: /builds/slave/test/build 15:32:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:32:32 INFO - Running post-action listener: _resource_record_post_action 15:32:32 INFO - ##### 15:32:32 INFO - ##### Finished clobber step (success) 15:32:32 INFO - ##### 15:32:32 INFO - ##### 15:32:32 INFO - ##### Running read-buildbot-config step. 15:32:32 INFO - ##### 15:32:32 INFO - Running pre-action listener: _resource_record_pre_action 15:32:32 INFO - Running main action method: read_buildbot_config 15:32:32 INFO - Using buildbot properties: 15:32:32 INFO - { 15:32:32 INFO - "project": "", 15:32:32 INFO - "product": "firefox", 15:32:32 INFO - "script_repo_revision": "production", 15:32:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:32:32 INFO - "repository": "", 15:32:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:32:32 INFO - "buildid": "20160627140517", 15:32:32 INFO - "pgo_build": "False", 15:32:32 INFO - "basedir": "/builds/slave/test", 15:32:32 INFO - "buildnumber": 89, 15:32:32 INFO - "slavename": "talos-linux64-ix-004", 15:32:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:32:32 INFO - "platform": "ubuntu64_hw", 15:32:32 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:32:32 INFO - "branch": "mozilla-central", 15:32:32 INFO - "repo_path": "mozilla-central", 15:32:32 INFO - "revision": "d17c6eee724effadd6138dd1ced77bd7beaa4f9c", 15:32:32 INFO - "stage_platform": "linux64", 15:32:32 INFO - "builduid": "c7c5f701e90347e6b023a3355f5c744b", 15:32:32 INFO - "slavebuilddir": "test" 15:32:32 INFO - } 15:32:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:32:32 INFO - Running post-action listener: _resource_record_post_action 15:32:32 INFO - ##### 15:32:32 INFO - ##### Finished read-buildbot-config step (success) 15:32:32 INFO - ##### 15:32:32 INFO - ##### 15:32:32 INFO - ##### Running download-and-extract step. 15:32:32 INFO - ##### 15:32:32 INFO - Running pre-action listener: _resource_record_pre_action 15:32:32 INFO - Running main action method: download_and_extract 15:32:32 INFO - mkdir: /builds/slave/test/build/tests 15:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:32 INFO - https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:32:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:32:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:32:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:32:33 INFO - Downloaded 1361 bytes. 15:32:33 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:32:33 INFO - Using the following test package requirements: 15:32:33 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:32:33 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:32:33 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:32:33 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'jsshell-linux-x86_64.zip'], 15:32:33 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:32:33 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:32:33 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:32:33 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:32:33 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:32:33 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:33 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:32:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:33 INFO - https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:32:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:32:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:32:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:34 INFO - Downloaded 22713290 bytes. 15:32:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:32:35 INFO - Return code: 0 15:32:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:32:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:32:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:32:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:36 INFO - Downloaded 22713290 bytes. 15:32:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:32:38 INFO - Return code: 0 15:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:38 INFO - https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:32:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:32:39 INFO - Downloaded 14405240 bytes. 15:32:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:32:39 INFO - Return code: 0 15:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:39 INFO - https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:32:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:32:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:32:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:32:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:32:40 INFO - Downloaded 56378524 bytes. 15:32:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:32:40 INFO - mkdir: /builds/slave/test/properties 15:32:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:32:40 INFO - Writing to file /builds/slave/test/properties/build_url 15:32:40 INFO - Contents: 15:32:40 INFO - build_url:https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:32:41 INFO - Running post-action listener: _resource_record_post_action 15:32:41 INFO - Running post-action listener: set_extra_try_arguments 15:32:41 INFO - ##### 15:32:41 INFO - ##### Finished download-and-extract step (success) 15:32:41 INFO - ##### 15:32:41 INFO - ##### 15:32:41 INFO - ##### Running populate-webroot step. 15:32:41 INFO - ##### 15:32:41 INFO - Running pre-action listener: _resource_record_pre_action 15:32:41 INFO - Running main action method: populate_webroot 15:32:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:32:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:32:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:32:41 INFO - '--tppagecycles', 15:32:41 INFO - '7'], 15:32:41 INFO - 'tests': ['tsvgm']}}, 15:32:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:32:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:32:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:32:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:32:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['damp', 'tps']}, 15:32:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'tests': ['damp', 'tps']}, 15:32:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['dromaeo_dom']}, 15:32:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:32:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['basic_compositor_video']}, 15:32:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:32:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['a11yr', 15:32:41 INFO - 'ts_paint', 15:32:41 INFO - 'tpaint', 15:32:41 INFO - 'sessionrestore', 15:32:41 INFO - 'sessionrestore_no_auto_restore', 15:32:41 INFO - 'tabpaint']}, 15:32:41 INFO - 'other-e10s': {'tests': ['a11yr', 15:32:41 INFO - 'ts_paint', 15:32:41 INFO - 'tpaint', 15:32:41 INFO - 'sessionrestore', 15:32:41 INFO - 'sessionrestore_no_auto_restore', 15:32:41 INFO - 'tabpaint']}, 15:32:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:32:41 INFO - 'ts_paint', 15:32:41 INFO - 'tpaint', 15:32:41 INFO - 'sessionrestore', 15:32:41 INFO - 'sessionrestore_no_auto_restore', 15:32:41 INFO - 'tabpaint']}, 15:32:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:32:41 INFO - 'ts_paint', 15:32:41 INFO - 'tpaint', 15:32:41 INFO - 'sessionrestore', 15:32:41 INFO - 'sessionrestore_no_auto_restore', 15:32:41 INFO - 'tabpaint']}, 15:32:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['a11yr', 15:32:41 INFO - 'ts_paint', 15:32:41 INFO - 'tpaint', 15:32:41 INFO - 'sessionrestore', 15:32:41 INFO - 'sessionrestore_no_auto_restore', 15:32:41 INFO - 'tabpaint']}, 15:32:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['a11yr', 15:32:41 INFO - 'ts_paint', 15:32:41 INFO - 'tpaint', 15:32:41 INFO - 'sessionrestore', 15:32:41 INFO - 'sessionrestore_no_auto_restore', 15:32:41 INFO - 'tabpaint']}, 15:32:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['tsvgx', 15:32:41 INFO - 'tsvgr_opacity', 15:32:41 INFO - 'tart', 15:32:41 INFO - 'tscrollx', 15:32:41 INFO - 'cart']}, 15:32:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:32:41 INFO - 'tsvgr_opacity', 15:32:41 INFO - 'tart', 15:32:41 INFO - 'tscrollx', 15:32:41 INFO - 'cart']}, 15:32:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'talos_options': ['--disable-e10s'], 15:32:41 INFO - 'tests': ['tp5o']}, 15:32:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'tests': ['tp5o']}, 15:32:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'talos_options': ['--disable-e10s', 15:32:41 INFO - '--xperf_path', 15:32:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:41 INFO - 'tests': ['tp5n']}, 15:32:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:41 INFO - 'talos_options': ['--xperf_path', 15:32:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:41 INFO - 'tests': ['tp5n']}}, 15:32:41 INFO - 'talos.zip': {'path': '', 15:32:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:32:41 INFO - Running post-action listener: _resource_record_post_action 15:32:41 INFO - ##### 15:32:41 INFO - ##### Finished populate-webroot step (success) 15:32:41 INFO - ##### 15:32:41 INFO - ##### 15:32:41 INFO - ##### Running create-virtualenv step. 15:32:41 INFO - ##### 15:32:41 INFO - Running pre-action listener: _resource_record_pre_action 15:32:41 INFO - Running main action method: create_virtualenv 15:32:41 INFO - Creating virtualenv /builds/slave/test/build/venv 15:32:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:32:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:32:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:32:41 INFO - Using real prefix '/usr' 15:32:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:32:42 INFO - Installing distribute.............................................................................................................................................................................................done. 15:32:44 INFO - Installing pip.................done. 15:32:44 INFO - Return code: 0 15:32:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:32:44 INFO - Using env: {'DISPLAY': ':0', 15:32:44 INFO - 'HOME': '/home/cltbld', 15:32:44 INFO - 'LANG': 'en_US.UTF-8', 15:32:44 INFO - 'LANGUAGE': 'en_US:en', 15:32:44 INFO - 'LOGNAME': 'cltbld', 15:32:44 INFO - 'MAIL': '/var/mail/cltbld', 15:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:44 INFO - 'MOZ_NO_REMOTE': '1', 15:32:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:44 INFO - 'NO_EM_RESTART': '1', 15:32:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:44 INFO - 'PWD': '/builds/slave/test', 15:32:44 INFO - 'SHELL': '/bin/bash', 15:32:44 INFO - 'SHLVL': '1', 15:32:44 INFO - 'TERM': 'linux', 15:32:44 INFO - 'TMOUT': '86400', 15:32:44 INFO - 'USER': 'cltbld', 15:32:44 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:44 INFO - '_': '/tools/buildbot/bin/python'} 15:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:32:44 INFO - Cleaning up... 15:32:44 INFO - Return code: 0 15:32:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:32:44 INFO - Using env: {'DISPLAY': ':0', 15:32:44 INFO - 'HOME': '/home/cltbld', 15:32:44 INFO - 'LANG': 'en_US.UTF-8', 15:32:44 INFO - 'LANGUAGE': 'en_US:en', 15:32:44 INFO - 'LOGNAME': 'cltbld', 15:32:44 INFO - 'MAIL': '/var/mail/cltbld', 15:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:44 INFO - 'MOZ_NO_REMOTE': '1', 15:32:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:44 INFO - 'NO_EM_RESTART': '1', 15:32:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:44 INFO - 'PWD': '/builds/slave/test', 15:32:44 INFO - 'SHELL': '/bin/bash', 15:32:44 INFO - 'SHLVL': '1', 15:32:44 INFO - 'TERM': 'linux', 15:32:44 INFO - 'TMOUT': '86400', 15:32:44 INFO - 'USER': 'cltbld', 15:32:44 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:44 INFO - '_': '/tools/buildbot/bin/python'} 15:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:44 INFO - Downloading/unpacking psutil>=3.1.1 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:32:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:46 INFO - Installing collected packages: psutil 15:32:46 INFO - Running setup.py install for psutil 15:32:46 INFO - building 'psutil._psutil_linux' extension 15:32:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:32:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:32:46 INFO - building 'psutil._psutil_posix' extension 15:32:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:32:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:32:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:46 INFO - Successfully installed psutil 15:32:46 INFO - Cleaning up... 15:32:47 INFO - Return code: 0 15:32:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:32: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 mozsystemmonitor==0.3 15:32:47 INFO - Using env: {'DISPLAY': ':0', 15:32:47 INFO - 'HOME': '/home/cltbld', 15:32:47 INFO - 'LANG': 'en_US.UTF-8', 15:32:47 INFO - 'LANGUAGE': 'en_US:en', 15:32:47 INFO - 'LOGNAME': 'cltbld', 15:32:47 INFO - 'MAIL': '/var/mail/cltbld', 15:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:47 INFO - 'MOZ_NO_REMOTE': '1', 15:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:47 INFO - 'NO_EM_RESTART': '1', 15:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:47 INFO - 'PWD': '/builds/slave/test', 15:32:47 INFO - 'SHELL': '/bin/bash', 15:32:47 INFO - 'SHLVL': '1', 15:32:47 INFO - 'TERM': 'linux', 15:32:47 INFO - 'TMOUT': '86400', 15:32:47 INFO - 'USER': 'cltbld', 15:32:47 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:47 INFO - '_': '/tools/buildbot/bin/python'} 15:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:32:49 INFO - Installing collected packages: mozsystemmonitor 15:32:49 INFO - Running setup.py install for mozsystemmonitor 15:32:49 INFO - Successfully installed mozsystemmonitor 15:32:49 INFO - Cleaning up... 15:32:49 INFO - Return code: 0 15:32:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:49 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:32:49 INFO - Using env: {'DISPLAY': ':0', 15:32:49 INFO - 'HOME': '/home/cltbld', 15:32:49 INFO - 'LANG': 'en_US.UTF-8', 15:32:49 INFO - 'LANGUAGE': 'en_US:en', 15:32:49 INFO - 'LOGNAME': 'cltbld', 15:32:49 INFO - 'MAIL': '/var/mail/cltbld', 15:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:49 INFO - 'MOZ_NO_REMOTE': '1', 15:32:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:49 INFO - 'NO_EM_RESTART': '1', 15:32:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:49 INFO - 'PWD': '/builds/slave/test', 15:32:49 INFO - 'SHELL': '/bin/bash', 15:32:49 INFO - 'SHLVL': '1', 15:32:49 INFO - 'TERM': 'linux', 15:32:49 INFO - 'TMOUT': '86400', 15:32:49 INFO - 'USER': 'cltbld', 15:32:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:49 INFO - '_': '/tools/buildbot/bin/python'} 15:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:49 INFO - Downloading/unpacking jsonschema==2.5.1 15:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:32:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:51 INFO - Downloading functools32-3.2.3-2.tar.gz 15:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:32:51 INFO - warning: no files found matching '*.txt' 15:32:51 INFO - no previously-included directories found matching 'build' 15:32:51 INFO - no previously-included directories found matching 'dist' 15:32:51 INFO - no previously-included directories found matching '.git*' 15:32:51 INFO - Installing collected packages: jsonschema, functools32 15:32:51 INFO - Running setup.py install for functools32 15:32:51 INFO - warning: no files found matching '*.txt' 15:32:51 INFO - no previously-included directories found matching 'build' 15:32:51 INFO - no previously-included directories found matching 'dist' 15:32:51 INFO - no previously-included directories found matching '.git*' 15:32:51 INFO - Successfully installed jsonschema functools32 15:32:51 INFO - Cleaning up... 15:32:51 INFO - Return code: 0 15:32:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:32:51 INFO - Using env: {'DISPLAY': ':0', 15:32:51 INFO - 'HOME': '/home/cltbld', 15:32:51 INFO - 'LANG': 'en_US.UTF-8', 15:32:51 INFO - 'LANGUAGE': 'en_US:en', 15:32:51 INFO - 'LOGNAME': 'cltbld', 15:32:51 INFO - 'MAIL': '/var/mail/cltbld', 15:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:51 INFO - 'MOZ_NO_REMOTE': '1', 15:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:51 INFO - 'NO_EM_RESTART': '1', 15:32:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:51 INFO - 'PWD': '/builds/slave/test', 15:32:51 INFO - 'SHELL': '/bin/bash', 15:32:51 INFO - 'SHLVL': '1', 15:32:51 INFO - 'TERM': 'linux', 15:32:51 INFO - 'TMOUT': '86400', 15:32:51 INFO - 'USER': 'cltbld', 15:32:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:51 INFO - '_': '/tools/buildbot/bin/python'} 15:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:52 INFO - Downloading/unpacking blobuploader==1.2.4 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32:53 INFO - Downloading blobuploader-1.2.4.tar.gz 15:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:32:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:32:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:54 INFO - Downloading docopt-0.6.1.tar.gz 15:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:32:54 INFO - Installing collected packages: blobuploader, requests, docopt 15:32:54 INFO - Running setup.py install for blobuploader 15:32:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:32:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:32:54 INFO - Running setup.py install for requests 15:32:55 INFO - Running setup.py install for docopt 15:32:55 INFO - Successfully installed blobuploader requests docopt 15:32:55 INFO - Cleaning up... 15:32:55 INFO - Return code: 0 15:32:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:55 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:32:55 INFO - Using env: {'DISPLAY': ':0', 15:32:55 INFO - 'HOME': '/home/cltbld', 15:32:55 INFO - 'LANG': 'en_US.UTF-8', 15:32:55 INFO - 'LANGUAGE': 'en_US:en', 15:32:55 INFO - 'LOGNAME': 'cltbld', 15:32:55 INFO - 'MAIL': '/var/mail/cltbld', 15:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:55 INFO - 'MOZ_NO_REMOTE': '1', 15:32:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:55 INFO - 'NO_EM_RESTART': '1', 15:32:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:55 INFO - 'PWD': '/builds/slave/test', 15:32:55 INFO - 'SHELL': '/bin/bash', 15:32:55 INFO - 'SHLVL': '1', 15:32:55 INFO - 'TERM': 'linux', 15:32:55 INFO - 'TMOUT': '86400', 15:32:55 INFO - 'USER': 'cltbld', 15:32:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:55 INFO - '_': '/tools/buildbot/bin/python'} 15:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:55 INFO - Running setup.py (path:/tmp/pip-OB8Vtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:55 INFO - Running setup.py (path:/tmp/pip-zanp6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:55 INFO - Running setup.py (path:/tmp/pip-IGeA1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:55 INFO - Running setup.py (path:/tmp/pip-fybGKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:55 INFO - Running setup.py (path:/tmp/pip-ymZE7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:55 INFO - Running setup.py (path:/tmp/pip-Dm66Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:56 INFO - Running setup.py (path:/tmp/pip-36Dn8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:56 INFO - Running setup.py (path:/tmp/pip-GvluM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:56 INFO - Running setup.py (path:/tmp/pip-FLTjxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:56 INFO - Running setup.py (path:/tmp/pip-vL4At5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:56 INFO - Running setup.py (path:/tmp/pip-6VWWIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:56 INFO - Running setup.py (path:/tmp/pip-sf3oLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:56 INFO - Running setup.py (path:/tmp/pip-bgJPxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:56 INFO - Running setup.py (path:/tmp/pip-bTkKAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:56 INFO - Running setup.py (path:/tmp/pip-KnM7Gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:56 INFO - Running setup.py (path:/tmp/pip-UpqYz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:56 INFO - Running setup.py (path:/tmp/pip-mskaIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:32:56 INFO - Running setup.py install for manifestparser 15:32:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:32:56 INFO - Running setup.py install for mozcrash 15:32:57 INFO - Running setup.py install for mozdebug 15:32:57 INFO - Running setup.py install for mozdevice 15:32:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Running setup.py install for mozfile 15:32:57 INFO - Running setup.py install for mozhttpd 15:32:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Running setup.py install for mozinfo 15:32:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Running setup.py install for mozInstall 15:32:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Running setup.py install for mozleak 15:32:57 INFO - Running setup.py install for mozlog 15:32:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Running setup.py install for moznetwork 15:32:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:32:57 INFO - Running setup.py install for mozprocess 15:32:58 INFO - Running setup.py install for mozprofile 15:32:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:32:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:32:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:32:58 INFO - Running setup.py install for mozrunner 15:32:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:32:58 INFO - Running setup.py install for mozscreenshot 15:32:58 INFO - Running setup.py install for moztest 15:32:58 INFO - Running setup.py install for mozversion 15:32:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:32:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:32:58 INFO - Cleaning up... 15:32:58 INFO - Return code: 0 15:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:58 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:32:58 INFO - Using env: {'DISPLAY': ':0', 15:32:58 INFO - 'HOME': '/home/cltbld', 15:32:58 INFO - 'LANG': 'en_US.UTF-8', 15:32:58 INFO - 'LANGUAGE': 'en_US:en', 15:32:58 INFO - 'LOGNAME': 'cltbld', 15:32:58 INFO - 'MAIL': '/var/mail/cltbld', 15:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:58 INFO - 'MOZ_NO_REMOTE': '1', 15:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:58 INFO - 'NO_EM_RESTART': '1', 15:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:58 INFO - 'PWD': '/builds/slave/test', 15:32:58 INFO - 'SHELL': '/bin/bash', 15:32:58 INFO - 'SHLVL': '1', 15:32:58 INFO - 'TERM': 'linux', 15:32:58 INFO - 'TMOUT': '86400', 15:32:58 INFO - 'USER': 'cltbld', 15:32:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:58 INFO - '_': '/tools/buildbot/bin/python'} 15:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:58 INFO - Running setup.py (path:/tmp/pip-Z9tPXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:58 INFO - Running setup.py (path:/tmp/pip-e2SThu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:59 INFO - Running setup.py (path:/tmp/pip-9ekEVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:59 INFO - Running setup.py (path:/tmp/pip-1Okkln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:59 INFO - Running setup.py (path:/tmp/pip-n4DPQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:59 INFO - Running setup.py (path:/tmp/pip-auZL31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:59 INFO - Running setup.py (path:/tmp/pip-61PIJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:59 INFO - Running setup.py (path:/tmp/pip-1i1fBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:59 INFO - Running setup.py (path:/tmp/pip-sHygCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:59 INFO - Running setup.py (path:/tmp/pip-18WVv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:59 INFO - Running setup.py (path:/tmp/pip-nUdfbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:59 INFO - Running setup.py (path:/tmp/pip-qSLsdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:59 INFO - Running setup.py (path:/tmp/pip-INSYJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:59 INFO - Running setup.py (path:/tmp/pip-n2erfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:59 INFO - Running setup.py (path:/tmp/pip-V2eu99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:59 INFO - Running setup.py (path:/tmp/pip-B57aGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:00 INFO - Running setup.py (path:/tmp/pip-g9W0OR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:33:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:02 INFO - Downloading blessings-1.6.tar.gz 15:33:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:33:02 INFO - Installing collected packages: blessings 15:33:02 INFO - Running setup.py install for blessings 15:33:02 INFO - Successfully installed blessings 15:33:02 INFO - Cleaning up... 15:33:02 INFO - Return code: 0 15:33:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:33:02 INFO - Reading from file tmpfile_stdout 15:33:02 INFO - Current package versions: 15:33:02 INFO - argparse == 1.2.1 15:33:02 INFO - blessings == 1.6 15:33:02 INFO - blobuploader == 1.2.4 15:33:02 INFO - docopt == 0.6.1 15:33:02 INFO - functools32 == 3.2.3-2 15:33:02 INFO - jsonschema == 2.5.1 15:33:02 INFO - manifestparser == 1.1 15:33:02 INFO - mozInstall == 1.12 15:33:02 INFO - mozcrash == 0.17 15:33:02 INFO - mozdebug == 0.1 15:33:02 INFO - mozdevice == 0.48 15:33:02 INFO - mozfile == 1.2 15:33:02 INFO - mozhttpd == 0.7 15:33:02 INFO - mozinfo == 0.9 15:33:02 INFO - mozleak == 0.1 15:33:02 INFO - mozlog == 3.2 15:33:02 INFO - moznetwork == 0.27 15:33:02 INFO - mozprocess == 0.22 15:33:02 INFO - mozprofile == 0.28 15:33:02 INFO - mozrunner == 6.11 15:33:02 INFO - mozscreenshot == 0.1 15:33:02 INFO - mozsystemmonitor == 0.3 15:33:02 INFO - moztest == 0.7 15:33:02 INFO - mozversion == 1.4 15:33:02 INFO - psutil == 3.1.1 15:33:02 INFO - requests == 1.2.3 15:33:02 INFO - wsgiref == 0.1.2 15:33:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:02 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:33:02 INFO - Using env: {'DISPLAY': ':0', 15:33:02 INFO - 'HOME': '/home/cltbld', 15:33:02 INFO - 'LANG': 'en_US.UTF-8', 15:33:02 INFO - 'LANGUAGE': 'en_US:en', 15:33:02 INFO - 'LOGNAME': 'cltbld', 15:33:02 INFO - 'MAIL': '/var/mail/cltbld', 15:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:02 INFO - 'MOZ_NO_REMOTE': '1', 15:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:02 INFO - 'NO_EM_RESTART': '1', 15:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:02 INFO - 'PWD': '/builds/slave/test', 15:33:02 INFO - 'SHELL': '/bin/bash', 15:33:02 INFO - 'SHLVL': '1', 15:33:02 INFO - 'TERM': 'linux', 15:33:02 INFO - 'TMOUT': '86400', 15:33:02 INFO - 'USER': 'cltbld', 15:33:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:02 INFO - '_': '/tools/buildbot/bin/python'} 15:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:33:02 INFO - Cleaning up... 15:33:02 INFO - Return code: 0 15:33:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:02 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:33:02 INFO - Using env: {'DISPLAY': ':0', 15:33:02 INFO - 'HOME': '/home/cltbld', 15:33:02 INFO - 'LANG': 'en_US.UTF-8', 15:33:02 INFO - 'LANGUAGE': 'en_US:en', 15:33:02 INFO - 'LOGNAME': 'cltbld', 15:33:02 INFO - 'MAIL': '/var/mail/cltbld', 15:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:02 INFO - 'MOZ_NO_REMOTE': '1', 15:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:02 INFO - 'NO_EM_RESTART': '1', 15:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:02 INFO - 'PWD': '/builds/slave/test', 15:33:02 INFO - 'SHELL': '/bin/bash', 15:33:02 INFO - 'SHLVL': '1', 15:33:02 INFO - 'TERM': 'linux', 15:33:02 INFO - 'TMOUT': '86400', 15:33:02 INFO - 'USER': 'cltbld', 15:33:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:02 INFO - '_': '/tools/buildbot/bin/python'} 15:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:33:02 INFO - Cleaning up... 15:33:02 INFO - Return code: 0 15:33:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:02 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 0x7fa0b5e47f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d33c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:33:02 INFO - Using env: {'DISPLAY': ':0', 15:33:02 INFO - 'HOME': '/home/cltbld', 15:33:02 INFO - 'LANG': 'en_US.UTF-8', 15:33:02 INFO - 'LANGUAGE': 'en_US:en', 15:33:02 INFO - 'LOGNAME': 'cltbld', 15:33:02 INFO - 'MAIL': '/var/mail/cltbld', 15:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:02 INFO - 'MOZ_NO_REMOTE': '1', 15:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:02 INFO - 'NO_EM_RESTART': '1', 15:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:02 INFO - 'PWD': '/builds/slave/test', 15:33:02 INFO - 'SHELL': '/bin/bash', 15:33:02 INFO - 'SHLVL': '1', 15:33:02 INFO - 'TERM': 'linux', 15:33:02 INFO - 'TMOUT': '86400', 15:33:02 INFO - 'USER': 'cltbld', 15:33:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:02 INFO - '_': '/tools/buildbot/bin/python'} 15:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:03 INFO - Downloading/unpacking flake8 15:33: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 15:33: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 15:33: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 15:33: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 15:33: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 15:33: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 15:33:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:33:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:33:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:33:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:33:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:33:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:33:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:33:05 INFO - no previously-included directories found matching 'docs/_build' 15:33:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:33:05 INFO - Running setup.py install for pep8 15:33:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:33:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:33:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:33:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:33:05 INFO - no previously-included directories found matching 'docs/_build' 15:33:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:33:05 INFO - Cleaning up... 15:33:05 INFO - Return code: 0 15:33:05 INFO - Running post-action listener: _resource_record_post_action 15:33:05 INFO - Running post-action listener: _start_resource_monitoring 15:33:06 INFO - Starting resource monitoring. 15:33:06 INFO - ##### 15:33:06 INFO - ##### Finished create-virtualenv step (success) 15:33:06 INFO - ##### 15:33:06 INFO - ##### 15:33:06 INFO - ##### Running install step. 15:33:06 INFO - ##### 15:33:06 INFO - Running pre-action listener: _resource_record_pre_action 15:33:06 INFO - Running main action method: install 15:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:33:06 INFO - Reading from file tmpfile_stdout 15:33:06 INFO - Detecting whether we're running mozinstall >=1.0... 15:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:33:06 INFO - Reading from file tmpfile_stdout 15:33:06 INFO - Output received: 15:33:06 INFO - Usage: mozinstall [options] installer 15:33:06 INFO - Options: 15:33:06 INFO - -h, --help show this help message and exit 15:33:06 INFO - -d DEST, --destination=DEST 15:33:06 INFO - Directory to install application into. [default: 15:33:06 INFO - "/builds/slave/test"] 15:33:06 INFO - --app=APP Application being installed. [default: firefox] 15:33:06 INFO - mkdir: /builds/slave/test/build/application 15:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:33:22 INFO - Reading from file tmpfile_stdout 15:33:22 INFO - Output received: 15:33:22 INFO - /builds/slave/test/build/application/firefox/firefox 15:33:22 INFO - Running post-action listener: _resource_record_post_action 15:33:22 INFO - ##### 15:33:22 INFO - ##### Finished install step (success) 15:33:22 INFO - ##### 15:33:22 INFO - ##### 15:33:22 INFO - ##### Running run-tests step. 15:33:22 INFO - ##### 15:33:22 INFO - Running pre-action listener: _resource_record_pre_action 15:33:22 INFO - Running main action method: run_tests 15:33:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:33:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:33:22 INFO - Python 2.7.3 15:33:22 INFO - Return code: 0 15:33:22 INFO - grabbing minidump binary from tooltool 15:33:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:22 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 0x1dc3710>, '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 0x1dc4610>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc4aa0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:33:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:33:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:33:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:33:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:33:22 INFO - Return code: 0 15:33:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:33:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:33:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:33:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:33:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:33:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:33:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:33:23 INFO - Return code: 0 15:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:33:23 INFO - Using env: {'DISPLAY': ':0', 15:33:23 INFO - 'HOME': '/home/cltbld', 15:33:23 INFO - 'LANG': 'en_US.UTF-8', 15:33:23 INFO - 'LANGUAGE': 'en_US:en', 15:33:23 INFO - 'LOGNAME': 'cltbld', 15:33:23 INFO - 'MAIL': '/var/mail/cltbld', 15:33:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:33:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:23 INFO - 'MOZ_NO_REMOTE': '1', 15:33:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:33:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:23 INFO - 'NO_EM_RESTART': '1', 15:33:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:23 INFO - 'PWD': '/builds/slave/test', 15:33:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:33:23 INFO - 'SHELL': '/bin/bash', 15:33:23 INFO - 'SHLVL': '1', 15:33:23 INFO - 'TERM': 'linux', 15:33:23 INFO - 'TMOUT': '86400', 15:33:23 INFO - 'USER': 'cltbld', 15:33:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857', 15:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:23 INFO - '_': '/tools/buildbot/bin/python'} 15:33:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:33:23 INFO - mozversion application_buildid: 20160627140517 15:33:23 INFO - mozversion application_changeset: d17c6eee724effadd6138dd1ced77bd7beaa4f9c 15:33:23 INFO - mozversion application_display_name: Nightly 15:33:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:33:23 INFO - mozversion application_name: Firefox 15:33:23 INFO - mozversion application_remotingname: firefox 15:33:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:33:23 INFO - mozversion application_vendor: Mozilla 15:33:23 INFO - mozversion application_version: 50.0a1 15:33:23 INFO - mozversion platform_buildid: 20160627140517 15:33:23 INFO - mozversion platform_changeset: d17c6eee724effadd6138dd1ced77bd7beaa4f9c 15:33:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:33:23 INFO - mozversion platform_version: 50.0a1 15:33:23 INFO - using testdate: 1467066803 15:33:23 INFO - actual date: 1467066803 15:33:23 INFO - starting webserver on 'localhost:35675' 15:33:23 INFO - SUITE-START | Running 2 tests 15:33:23 INFO - TEST-START | dromaeo_css 15:33:23 INFO - operating with platform_type : linux_ 15:33:23 INFO - Initialising browser for dromaeo_css test... 15:33:23 INFO - TEST-INFO | started process 22271 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYcDNVK/profile http://localhost:35675/getInfo.html) 15:33:28 INFO - PROCESS | 22271 | __metrics Screen width/height:1600/1200 15:33:28 INFO - PROCESS | 22271 | colorDepth:24 15:33:28 INFO - PROCESS | 22271 | Browser inner width/height: 1024/697 15:33:28 INFO - PROCESS | 22271 | __metrics 15:33:29 ERROR - PROCESS | 22271 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 15:33:30 INFO - TEST-INFO | 22271: exit 0 15:33:30 INFO - Browser initialized. 15:33:30 INFO - Running cycle 1/1 for dromaeo_css test... 15:33:30 INFO - TEST-INFO | started process 22392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYcDNVK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:42 INFO - PROCESS | 22392 | RSS: Main: 186384384 15:33:42 INFO - PROCESS | 22392 | 15:34:32 INFO - PROCESS | 22392 | 1467066872316 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:36:44 INFO - PROCESS | 22392 | Cycle 1(1): loaded http://localhost:35675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35675/tests/dromaeo/cssquery-ext.html) 15:36:45 INFO - PROCESS | 22392 | RSS: Main: 189165568 15:36:45 INFO - PROCESS | 22392 | 15:39:48 INFO - PROCESS | 22392 | Cycle 1(1): loaded http://localhost:35675/tests/dromaeo/cssquery-ext.html (next: http://localhost:35675/tests/dromaeo/cssquery-jquery.html) 15:39:48 INFO - PROCESS | 22392 | RSS: Main: 188018688 15:39:48 INFO - PROCESS | 22392 | 15:42:50 INFO - PROCESS | 22392 | Cycle 1(1): loaded http://localhost:35675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35675/tests/dromaeo/cssquery-mootools.html) 15:42:51 INFO - PROCESS | 22392 | RSS: Main: 189198336 15:42:51 INFO - PROCESS | 22392 | 15:45:53 INFO - PROCESS | 22392 | Cycle 1(1): loaded http://localhost:35675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35675/tests/dromaeo/cssquery-prototype.html) 15:45:53 INFO - PROCESS | 22392 | RSS: Main: 186847232 15:45:53 INFO - PROCESS | 22392 | 15:48:56 INFO - PROCESS | 22392 | Cycle 1(1): loaded http://localhost:35675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35675/tests/dromaeo/cssquery-yui.html) 15:48:57 INFO - PROCESS | 22392 | RSS: Main: 188792832 15:48:57 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | Cycle 1(1): loaded http://localhost:35675/tests/dromaeo/cssquery-yui.html (next: http://localhost:35675/tests/dromaeo/cssquery-dojo.html) 15:51:59 INFO - PROCESS | 22392 | RSS: Main: 189915136 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | __start_tp_report 15:51:59 INFO - PROCESS | 22392 | _x_x_mozilla_page_load 15:51:59 INFO - PROCESS | 22392 | _x_x_mozilla_page_load_details 15:51:59 INFO - PROCESS | 22392 | |i|pagename|runs| 15:51:59 INFO - PROCESS | 22392 | |0;dojo.html;2641.564141916583;2699.4601079784034;2712.51174788538;2718.1845446366087;2722.0881004863368;4890.24201248807;4899.608031357492;4920.803167873285;4958.826441074554;4980.750962451872;4861.9513804861945;4899.6325275604295;4905.3132561441425;4922.237053256751;4977.402711674604;4457.866264012085;4474.194644963905;4544.250198717207;4577.130345234412;4588.197065513538;627.5458360298576;4436.933445998313;4498.392716983214;4531.4109165808395;4573.085382923428;4540.954590454103;4561.931571026437;4564.726116433025;4570.177368073745;4572.5427457254345;4536.364908912755;4541.205289074399;4554.95445045552;4578.450585929677;4603.608693261077;4362.47650281965;4448.822047118111;4453.465584129893;4455.977720111411;4457.2199865023495;4791.47293797682;4828.203346447832;4872.3422337984275;4902.975485122587;4963.379577552806;5229.555487783505;5257.053730328539;5269;5276.604254680914;5342.332208473941;5318.654287471302;5357.276767636397;5364.9731751341;5375.43557926416;5415.6479828811;4950.777215025332;4956.826511072131;4959.08256972454;4960.131976904027;4965.255211718199;5331.413544510101;5374.059539580636;5384.165454354581;5446.727663616883;5462.262594549765;5179.352580927384;5194.740262986911;5205.6616319939085;5222.4255331913455;5241.31862857826;5271.578273738091;5282.603804714662;5289.603279754034;5309.36287645485;5336.439673834269;5093.904810465768;5202;5226.033183860998;5273.973630131824;5288.762005709752;5243.108671525773;5262.210668399771;5274.287971123926;5275.261463395128;5292.947070529245;4567.611753001032;4570.7257564546235;4575.17646823575;4576.496585375606;4615.538446155425;3848;3869.0134015825784;3876.9224615507533;3891.3384724596885;3896.6687831534637;4538.047010127844;4550.089982003613;4586.449626044896;4619.884502887455;4640.23436133041;5306.389765176977;5326.680399176062;5356.812511562077;5366.812161574326;5369.5972802040005;4626.306054091846;4635.142498637695;4640.907181856344;4650.093231819763;4696.718196908197;4616.838410655611;4766.213574760195;4791.47293797689;4791.5687588117235;4809.663323567317;4996.150654388763;5011.824586139468;5037.19404895215;5044.621653376085;5093.23601459784;5375.032494150934;5383.273258110262;5386.192071189353;5397.055515284731;5398.352197736297;4912.238603016538;4947.529984651453;4948.406191257072;5008.824691135938;5023.397192336943;5237.973810130925;5258.447862974485;5265.6840589564745;5267.446918073547;5324.307839980777;5189.662671926313;5289.920651190312;5326.121190003606;5328.60035497347;5330.760115794876;5130.2817605536;5175.404828444777;5190.2214667800135;5193.61047921415;5206.453322401093;5140.30605868203;5158.871028224325;5184.300119483822;5204.8178313758835;5223.608229382736;5022.723750193774;5030.346055012825;5056.418511871182;5088.211327244283;5108;5157.613179011514;5180.119379705384;5197.168453047494;5224.7126408047925;5247.790088396421;5070.2394640802695;5071.54356107952;5076.847694569169;5078.238264260391;5132.12753831842 15:51:59 INFO - PROCESS | 22392 | |1;ext.html;545.0894137898057;578.2511647416595;583.9737211825468;596.1177457363102;607.565590602719;33135.171620709465;37367.43948840766;37428.25143497129;37463;37550.43674344887;38305.42541861867;39022.21955560887;39123;39163.21673566527;39209.41185882214;7633.6183190840375;7869.252421020023;7882.014748156481;7885.408594355417;7888.921110788905;7640.388768898475;7661.6169191540475;7740.535567865946;7744.457887947818;7762.30139287464;7850.921490785105;7896.763097107096;7906.367490600767;7914.643841027168;7918.287354138127;7736.883946740803;7827.647755850972;7836.804079897991;7865.331446827026;7875.724349647763;7745.031871016123;7792.2986931176465;7815.491993020435;7817;7829.099653539859;36646.26707465862;36740.63259367399;36812;36900.446493302625;36938.630613693786;3046.2384403899027;3095.77716801862;3096.349766549005;3181.681831816823;3361.159710072482;29445.852770736223;29490.11529654114;29904.8504757477;30370.54444183339;30415.23961900948;3293.5059741038795;3308.8180150091735;3324.1855745342305;3333.599968003831;3369.5956485221686;208138;267026;268137;268799;269930;54691.179632305546;55425.722871385384;55426.16860747092;55450.16824747632;55627.16559251615;32627.836860815543;33412.66587334096;33634.49548256778;35287.82356088254;35419.82290088585;13338.73322533544;13404;13460.192388456724;13480.86519134796;13561.660958475919;10705.518251678694;10788.352698837938;10832.78334433307;10840.349579025284;10874.130069594414;17872.374466893598;17924.372646957294;17972.460826175236;17977.191026403583;18066.1870215838;6827.624480653512;6866.277796579833;7072.7524536642;7098.609576473344;7163.641817909083;816.3795515408333;825.2160447574828;831.9958400208081;907.4950162158909;949.8812648418948;28325;28671.283217919303;28714.569281460794;28997.710022899922;29248.415031699245;993.7416271769392;997.2271489595621;1010.3584224017802;1015.3146626027402;1021.8518314844455;15037.924810375878;15176.241187940559;15343;15533.45632902843;15574.143422111665;385044;455073;463867;464425;465344;49688;49819.50180498149;50222.497775022515;50310.24534631983;50381.74809125931;1167.4279602994472;1235.5304984105983;1238.7708273969345;1250.1811313589612;1261.0227074017894;26802.865985669945;28341.43317133687;28706.282342941595;28854.855725721238;29116.56325155082;41238.38142427926;41371.379429307985;41610.37584436296;41947.58052419437;42122;292376;292978;293603;294121;296450;36811.63188368082;36899;37419.43870841994;38146;39830;40098.39852402275;40384.79807600943;41330;41421.514709117546;41573.1685366297;35258.29483410369;35307.82346088253;35412.291754165286;35537.466937995436;35676;34632.48051279182;34720.652793471745;34852;35035.64964350426;35066.64933350736;34098.48852267169;34231.82884085563;36886.44670330001;37661.246775063795;40119.3982090263;32907.341853162325;33787.83106084454;35053.29893402169;35132.297354053284;36930;26043.218703438928;26084.86957565276;26242.868785655948;26420.603690945034;26705.397676139495 15:51:59 INFO - PROCESS | 22392 | |2;jquery.html;376.3132283582462;377.14012052520246;380.4901432081012;393.0841140143466;394.5896267881403;15564.252902864622;16861.241244144014;17290.56773580661;17670.028148451845;18088.638227235446;16656.916715416435;17052.23264953077;17218.655626887456;17391.82608173921;17526.12369381529;5127.641065125443;5313;5335.493128152819;5361.061814182522;5364.409914909357;3788.90527736806;5927.555433342495;6277.905831412532;6282.434580887719;6400.743970241208;6237.500999920018;6313.842153946165;6342.619442833422;6348.396902294275;6408.455281301095;6040.184575082351;6138.785142519991;6160.630362178239;6168.8766224675255;6218.782342618009;5245.423003469616;5370.865728356823;5497.367802702701;5528.585356098269;5547.945890280874;20773.480662983395;20803;20847.061882215337;20893.8955305224;21208.25771098004;1972.80271972803;1991.5519008223034;2048.7502623399714;2466.4080620651093;2540.2379286214245;13170.485394179601;13518.93240533791;13567.52513662027;13695.931520342434;13942.512012079529;1826.242109524559;1876.8686191966567;1885.1045753267226;1892.082340065081;1924.9903750481299;59296.40703592952;61773.382266177214;65423.672881635284;66626.66686666584;68068.31931680719;9837.163841073589;10027.749306267402;10248.590056397661;10366.222533309881;10461.110805581611;13950;14064;14090.506832260822;14093.577192684235;14095;7104.467164962649;7149.857002859913;7201.3518783309755;7334.229905859808;7358.889616655754;6708.798736037927;6709.496787740939;6733.696983635748;6885.414739747077;6907.033015377851;4018.8948039288844;4326.740395576276;4422.955770442319;4462.509123996358;4511.436070491189;1726.6028813372996;1814.8185181481747;1852.6016906364928;1901.153986476005;1910.510300793125;850.8298340331835;852.8933883264592;883.9071897450804;918.9356745027784;922.5479515037583;16325.183740812912;16749.413770517975;16838.410655626994;16921.576960576083;16921.576960576083;867.9869801952975;882.5013867165031;884.7434244069148;892.5537231384308;917.738444543302;8810.911890881009;9428.764280893032;9457.479838608942;9460;9498.762530936781;399726;400109;400164;400433;402108;49172;53002.469975300526;78248;78586;78900.6054969733;372.599455585247;386.5516001438318;388.5046565628846;389.09923527034823;390.0229924040261;9258.53707314618;9581.856272155785;9701.854472182782;10025.047620476043;10079.89920100819;27025;35250.64749352474;35446.64553354433;36908.81545592255;38072.42891356577;49526;50159.74920125376;52737.47262527326;53402.73298633482;53442.73278633582;7237.276272372721;7469.477136600386;7505.924940750522;7572.053493313336;7714.5756983366455;10673.786524269626;11183.608573699881;11327.263727857662;11634.767304653691;12174.391280435766;9679.9032009679;9887.703368898945;10062.547185376528;10166.847497287694;10288.434136122285;9778.16885564735;9857.852132217879;9896.208303335572;10372.792544148924;10459.529321180717;9361.344705870524;9422.199113075465;9437.292203084658;9489.715308540479;9621;7675.923240767521;7680.423968202519;7692.499987500794;7720.266574675396;7890.605469726605;8498.957505212435;9727.562259698188;10053.246006549392;10280.434576098087;10339.121174699934 15:51:59 INFO - PROCESS | 22392 | |3;mootools.html;1384.9584512464623;1413.201541129262;1489.3744627256551;1639.598298416888;1697.134461424673;6904.378605925465;7703;7749.92250077499;8221.342292616584;8235.547044912535;7723.884141737878;8012.078610959741;8083.272505474506;8099.109097999215;8118.431709780318;2196.5057861981045;2212.5021870079263;2225;2226.855254408458;2232.151782322715;2137.9037943292587;2209.5139069404786;2210.1159536185573;2216.556688662266;2223.499712564676;2178.9019494122804;2197.6923230747707;2205.5368372641765;2210.933671989843;2213.911443542256;2160.2007257314817;2206.8124209442217;2218.589560931217;2234.55308938213;2241.932742017742;2179.5096103376773;2216.966745498819;2223.8888055597154;2225.799678028969;2229.8885055747146;8012.8798068028445;8016.759497215035;8057;8065.475744076616;8068.878966815502;4382.715123516993;4504.09918016398;4529.886752831173;4549.499555048942;4551.476580193287;2835.5915630674617;2952.483315419793;2979.702029797025;2980.806247593921;2998.34036511967;1006.5772375602264;1053.6733612580124;1061.7186445591924;1068.8984546468073;1069.2408390043079;11194.44027798615;11194.496247668874;11239.494222759995;11242;11267.26762760418;4342.1532801103485;4381.452318460192;4398.3842262083335;4399.494058183286;4454.621357184611;9306;9393.436393816393;9436.197923176469;9529.666461673809;9530.046995300414;4593.356930029799;4669.789859456333;4679.836205732783;4686.882827929261;4705.270683044134;3804.0680033391995;4194.6853985951175;4276.764777937244;4284.828606855691;4338.58783415575;3758.7180961428003;3768.5854555998817;3832.271868345061;3886;3888.8444462221196;1230.8215308780177;1243.3783108445778;1247.5009996001672;1257.7484503099417;1262.665393670678;823.8352329534117;827.5076329583887;827.5159031966367;839.4291881520625;855.8373908957278;5057.31726216956;5228.581713462914;5260.868478288074;5272.182811664198;5353.411124776271;847.9067302596709;863.3869952333788;885.0750965241339;894.8389289927876;895.3329769321766;3432.1248081738995;3453.6546345365264;3470.3232869590693;3523.700485458765;3573.338932297533;9721.270904682176;10003.649872254437;10048.246381521416;10090.445025523666;10135.34120282194;9839.60641574329;10047.899521004696;10051.296409251283;10059.597616095421;10076.59693612262;7488.999999999891;7549;7617.200193979553;7658.770236892902;7679.424043196783;5060.544550990428;5275.366955965309;5354.196870469461;5375.973120134374;5397.136458166675;9256;9378.968313485648;9423.434593924387;9446.480443575394;9458.148766610762;9489.478078705737;9508.667196648084;9562.426254424758;9584.041595840361;9630.855537167088;5984.850378740392;6367.87264254703;6612.272650008592;6638.767643132467;6739.258681545124;7769.844603108019;7773;7802.258785415376;7817.648205830865;7852;6777.2883847195335;6991.475639327173;7034.648267586703;7085.291470852873;7156.427485801228;6802.217744959393;6858.828529286808;6898.517103802686;6954.200266969363;7068.434525238072;6052.757889684363;6059.666718330358;6100.389961004042;6123.418275263843;6135.509159267337;4517.774111294488;4548.795304211252;4588.242939914877;4599.862004139881;4625.236835922035;4630.143423466669;4673.158831410379;4685.203515402458;4726.716397016053;4760.428748550196 15:51:59 INFO - PROCESS | 22392 | |4;prototype.html;300.9939801203976;311.5000424318968;315.879965613067;316.55365934033017;319.17811635039766;4949.727764972925;5009.7996080156845;5086.160783470727;5221.817236396727;5229.764660590278;5103.515166059227;5114.718690472023;5126.256692779554;5126.282320475128;5138.922916156261;4053.7973101344887;4056.574059723731;4058.147788964321;4078.938815917764;4086.346184610463;3874.51494129673;4005.9399109013243;4045;4090.9181816363653;4116.958830411688;3934.3114954882926;3934.390169523714;3958.2875082485143;3961.227560625679;3965.702572307074;3981.820818063194;3983.800809959505;3994.500687414073;3997.2205419943125;4027.597240275978;3532.3994920863515;3549.3078849624335;3551.698105660996;3551.786892786415;3561.1809283864595;4859.878503037417;4976;4988.551030407244;5024.799008039674;5122.692638441668;4546.568076032771;4582.060677561092;4586.243269860503;4591.265397536378;4627.953720462786;5293.364796224478;5320.600954928396;5322.33470816148;5337.226102215157;5370.516653501204;3768.340540405418;3803.573659877546;3858.903527411809;3863.28529222092;3871.728978971473;5668.951244019829;5673.6879471629045;5696.6866822324755;5722;5728.455796699307;3966.2265858157384;3988.66096381805;3991.9201615967518;4000.6399424051974;4003.579624139482;3744.513213282256;3958.525921299188;4045.3122969094734;4093.385992101149;4102.384642303678;4479.932801007987;4490.708103973231;4505.040307466263;4518.661100417482;4524.366588677588;5350.2242174885205;5358.97320513395;5370.355557333145;5372.5433338166695;5399.190121481809;1297.6820678933723;1322.6693326668333;1357.3281225793296;1359.2184493916038;1371.8628137186402;850.7575341027778;851.1531026628544;871.4509858789049;877.3244601656688;882.5278476015301;4180.498340199135;4210.9683127633925;4222.767747773902;4236.703430759879;4276.481848943642;4828.082664293773;4894.972055868237;4899.877503062381;4919.458859525449;4935.654504184673;4228.365745138254;4238.6263692332395;4263.658907287409;4271.850485233002;4293.724326107168;2522.6846644169477;2607.674040744907;2625.4092829113297;2637.591173368131;2644.7884169266413;4576;4578.35179335439;4607.585317321457;4623.976880115578;4650.883727906829;4956.677815941952;4971.751412429364;4972.290101894476;5002.449730529639;5007.399112106497;4283.957160428356;4573.931391029138;4610.377599024089;4648.372469716613;4685.742284174266;4818.4217893852965;4849.612031037438;4856.417229932431;4898.436679781871;4937.679050861682;4632.652551058752;4664.250905910284;4737.313089601919;4784.258439941815;4790.7844147012775;4314.734764248795;4607.40103786519;4616.8614941551805;4618.468876079294;4642.744649044335;3524.559430071241;3533.363994481018;3561.590417101963;3563.3051554946537;3576.9432576268227;4862.92705609409;4879.146149423766;4889.242167464049;4891.437484689306;5298.284731561344;5292.814751483679;5425.647332923348;5431.429699881455;5437.510624043697;5452.890942181213;5412.944475827176;5433.592480563894;5434.424017035016;5452.754626041917;5457.508824205841;5072.949270507248;5302.443243459381;5322.787088516571;5323.228131920772;5380.973095134499;5413.646588352912;5451.890962180655;5456.563474922076;5458.317710286215;5485.341758988947;5270.679471932658;5305.522502974751;5330.733463326896;5336.119540275811;5363.704996225089 15:51:59 INFO - PROCESS | 22392 | |5;yui.html;654.3097032630576;707.8938159276108;728.4936968806679;745.7874505765858;756.121098763344;12819.615411537645;13282.218401375521;13522.526711565097;13523.59429217125;13541.119827211236;13092;13427.597172084854;13457.528986485475;13470.797938030937;13495.325233738324;629.9874002519947;776.2974508070206;791.4420333664767;802.1697543042923;807.9192080791933;807;808.2725547007682;813.6704634622987;814.0353680888167;816.0166998766501;851.3317046118798;856.8500512410303;857.7255278310943;858.935579831512;860.7503823891064;833.3250067445358;837.7068026190815;839.5046922315832;840.7855996720858;840.9075001749802;613.6103574230395;615.3600255734032;615.3600255734032;617.7405489694339;618.1870839845589;12936;12999.935000325033;13765.724685506333;14098;14104.647383815383;13936.372863221079;14265.215413152273;14271.500497482539;14286.499972500913;14456.132632042007;11131.165162612755;11174.888251117465;11182.944085279603;11233.775324493465;11275.605353812578;3437.8796742113905;3468.6704763047724;3509.736769742254;3533.840977156034;3600.3339382214376;66674.33325666729;67240.99138512975;67322.32677673259;67893.3210667897;68964.31035689628;11044.558217671203;11264.324140551593;11305.378204198683;11326.660200193843;11431.14266430021;20957.26649567258;21301.786982130292;21348.78651213499;21357.78642213558;21361.78638213598;7109.928900710926;7176.7846964591145;7260.891086633705;7263;7449.590272534954;3708.369577171887;3759.3045286622064;3787.4886890270013;3875.803104424755;3935.7244992850797;3004.0987703688807;3102.348506813594;3114.7819652624553;3120.8127512349333;3137.450946084426;497.2466712929914;512.9051125541787;514.9150390185653;515.9226116082617;522.4566450891031;536.7906516458506;562.3869981719945;566.8412844403491;568.556525909791;572.2589246925241;7365.447591430664;7384.852302954018;7498.962505187439;7660.502067365604;7677.270659287359;443.08502941425854;495.2719502331567;497.3385397421493;509.997450012755;534.5429657642737;3508.5438892943753;3690.797006164633;3798.506194194737;3912;3927.66614837742;53197.2020419694;78991.21008789954;83411;93412;96822;80332.19667803364;84535;84740.57629711812;85191.57404212939;85600.57199713962;74401.25598744051;74908.62545687346;77214.61392693;77799;80711;7370.520916140434;7563.357114645097;7586.051743532059;7812.140664527011;7849.6655568554625;38761.51146396838;43380.78309608432;44079;44534;44637.776811115735;55486.72256638691;64643;65851;66691.33308666851;68101.31898681149;11627.76744465089;11928.165028447926;12027.579034734092;12120;12431.689207769878;10971.122310215324;11202.327860328407;12029.879701202875;12568.434420451285;13085.672858178621;10989.670309890396;10990;11430.714232144262;11583.6524904253;11669.649910502698;10593.152547796004;10615.946920265349;10746.24776265654;10867.836982445111;11399.886001139883;10156.542955566869;10497.107745841384;10543.630972916219;10612.151027917902;10786.838197427202;9622.855657165008;9759.463229522444;9838.75403114928;9852.310338276251;9853.852192217266;8462.619182136696;8576.957115214384;8649.4810311379;8821.74686286012;8949.686760963335 15:51:59 INFO - PROCESS | 22392 | __end_tp_report 15:51:59 INFO - PROCESS | 22392 | __start_cc_report 15:51:59 INFO - PROCESS | 22392 | _x_x_mozilla_cycle_collect,403 15:51:59 INFO - PROCESS | 22392 | __end_cc_report 15:51:59 INFO - PROCESS | 22392 | __startTimestamp1467067919817__endTimestamp 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | ------- Summary: start ------- 15:51:59 INFO - PROCESS | 22392 | Number of tests: 6 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | [#0] dojo.html Cycles:165 Average:4869.39 Median:5030.35 stddev:622.45 (12.4%) stddev-sans-first:599.32 15:51:59 INFO - PROCESS | 22392 | Values: 2641.6 2699.5 2712.5 2718.2 2722.1 4890.2 4899.6 4920.8 4958.8 4980.8 4862.0 4899.6 4905.3 4922.2 4977.4 4457.9 4474.2 4544.3 4577.1 4588.2 627.5 4436.9 4498.4 4531.4 4573.1 4541.0 4561.9 4564.7 4570.2 4572.5 4536.4 4541.2 4555.0 4578.5 4603.6 4362.5 4448.8 4453.5 4456.0 4457.2 4791.5 4828.2 4872.3 4903.0 4963.4 5229.6 5257.1 5269.0 5276.6 5342.3 5318.7 5357.3 5365.0 5375.4 5415.6 4950.8 4956.8 4959.1 4960.1 4965.3 5331.4 5374.1 5384.2 5446.7 5462.3 5179.4 5194.7 5205.7 5222.4 5241.3 5271.6 5282.6 5289.6 5309.4 5336.4 5093.9 5202.0 5226.0 5274.0 5288.8 5243.1 5262.2 5274.3 5275.3 5292.9 4567.6 4570.7 4575.2 4576.5 4615.5 3848.0 3869.0 3876.9 3891.3 3896.7 4538.0 4550.1 4586.4 4619.9 4640.2 5306.4 5326.7 5356.8 5366.8 5369.6 4626.3 4635.1 4640.9 4650.1 4696.7 4616.8 4766.2 4791.5 4791.6 4809.7 4996.2 5011.8 5037.2 5044.6 5093.2 5375.0 5383.3 5386.2 5397.1 5398.4 4912.2 4947.5 4948.4 5008.8 5023.4 5238.0 5258.4 5265.7 5267.4 5324.3 5189.7 5289.9 5326.1 5328.6 5330.8 5130.3 5175.4 5190.2 5193.6 5206.5 5140.3 5158.9 5184.3 5204.8 5223.6 5022.7 5030.3 5056.4 5088.2 5108.0 5157.6 5180.1 5197.2 5224.7 5247.8 5070.2 5071.5 5076.8 5078.2 5132.1 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | [#1] ext.html Cycles:180 Average:48282.94 Median:36693.45 stddev:90715.85 (247.2%) stddev-sans-first:90899.52 15:51:59 INFO - PROCESS | 22392 | Values: 545.1 578.3 584.0 596.1 607.6 33135.2 37367.4 37428.3 37463.0 37550.4 38305.4 39022.2 39123.0 39163.2 39209.4 7633.6 7869.3 7882.0 7885.4 7888.9 7640.4 7661.6 7740.5 7744.5 7762.3 7850.9 7896.8 7906.4 7914.6 7918.3 7736.9 7827.6 7836.8 7865.3 7875.7 7745.0 7792.3 7815.5 7817.0 7829.1 36646.3 36740.6 36812.0 36900.4 36938.6 3046.2 3095.8 3096.3 3181.7 3361.2 29445.9 29490.1 29904.9 30370.5 30415.2 3293.5 3308.8 3324.2 3333.6 3369.6 208138.0 267026.0 268137.0 268799.0 269930.0 54691.2 55425.7 55426.2 55450.2 55627.2 32627.8 33412.7 33634.5 35287.8 35419.8 13338.7 13404.0 13460.2 13480.9 13561.7 10705.5 10788.4 10832.8 10840.3 10874.1 17872.4 17924.4 17972.5 17977.2 18066.2 6827.6 6866.3 7072.8 7098.6 7163.6 816.4 825.2 832.0 907.5 949.9 28325.0 28671.3 28714.6 28997.7 29248.4 993.7 997.2 1010.4 1015.3 1021.9 15037.9 15176.2 15343.0 15533.5 15574.1 385044.0 455073.0 463867.0 464425.0 465344.0 49688.0 49819.5 50222.5 50310.2 50381.7 1167.4 1235.5 1238.8 1250.2 1261.0 26802.9 28341.4 28706.3 28854.9 29116.6 41238.4 41371.4 41610.4 41947.6 42122.0 292376.0 292978.0 293603.0 294121.0 296450.0 36811.6 36899.0 37419.4 38146.0 39830.0 40098.4 40384.8 41330.0 41421.5 41573.2 35258.3 35307.8 35412.3 35537.5 35676.0 34632.5 34720.7 34852.0 35035.6 35066.6 34098.5 34231.8 36886.4 37661.2 40119.4 32907.3 33787.8 35053.3 35132.3 36930.0 26043.2 26084.9 26242.9 26420.6 26705.4 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | [#2] jquery.html Cycles:180 Average:24146.75 Median:49349.00 stddev:65823.47 (133.4%) stddev-sans-first:65983.92 15:51:59 INFO - PROCESS | 22392 | Values: 376.3 377.1 380.5 393.1 394.6 15564.3 16861.2 17290.6 17670.0 18088.6 16656.9 17052.2 17218.7 17391.8 17526.1 5127.6 5313.0 5335.5 5361.1 5364.4 3788.9 5927.6 6277.9 6282.4 6400.7 6237.5 6313.8 6342.6 6348.4 6408.5 6040.2 6138.8 6160.6 6168.9 6218.8 5245.4 5370.9 5497.4 5528.6 5547.9 20773.5 20803.0 20847.1 20893.9 21208.3 1972.8 1991.6 2048.8 2466.4 2540.2 13170.5 13518.9 13567.5 13695.9 13942.5 1826.2 1876.9 1885.1 1892.1 1925.0 59296.4 61773.4 65423.7 66626.7 68068.3 9837.2 10027.7 10248.6 10366.2 10461.1 13950.0 14064.0 14090.5 14093.6 14095.0 7104.5 7149.9 7201.4 7334.2 7358.9 6708.8 6709.5 6733.7 6885.4 6907.0 4018.9 4326.7 4423.0 4462.5 4511.4 1726.6 1814.8 1852.6 1901.2 1910.5 850.8 852.9 883.9 918.9 922.5 16325.2 16749.4 16838.4 16921.6 16921.6 868.0 882.5 884.7 892.6 917.7 8810.9 9428.8 9457.5 9460.0 9498.8 399726.0 400109.0 400164.0 400433.0 402108.0 49172.0 53002.5 78248.0 78586.0 78900.6 372.6 386.6 388.5 389.1 390.0 9258.5 9581.9 9701.9 10025.0 10079.9 27025.0 35250.6 35446.6 36908.8 38072.4 49526.0 50159.7 52737.5 53402.7 53442.7 7237.3 7469.5 7505.9 7572.1 7714.6 10673.8 11183.6 11327.3 11634.8 12174.4 9679.9 9887.7 10062.5 10166.8 10288.4 9778.2 9857.9 9896.2 10372.8 10459.5 9361.3 9422.2 9437.3 9489.7 9621.0 7675.9 7680.4 7692.5 7720.3 7890.6 8499.0 9727.6 10053.2 10280.4 10339.1 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | [#3] mootools.html Cycles:180 Average:5270.24 Median:4671.47 stddev:3003.09 (64.3%) stddev-sans-first:2997.32 15:51:59 INFO - PROCESS | 22392 | Values: 1385.0 1413.2 1489.4 1639.6 1697.1 6904.4 7703.0 7749.9 8221.3 8235.5 7723.9 8012.1 8083.3 8099.1 8118.4 2196.5 2212.5 2225.0 2226.9 2232.2 2137.9 2209.5 2210.1 2216.6 2223.5 2178.9 2197.7 2205.5 2210.9 2213.9 2160.2 2206.8 2218.6 2234.6 2241.9 2179.5 2217.0 2223.9 2225.8 2229.9 8012.9 8016.8 8057.0 8065.5 8068.9 4382.7 4504.1 4529.9 4549.5 4551.5 2835.6 2952.5 2979.7 2980.8 2998.3 1006.6 1053.7 1061.7 1068.9 1069.2 11194.4 11194.5 11239.5 11242.0 11267.3 4342.2 4381.5 4398.4 4399.5 4454.6 9306.0 9393.4 9436.2 9529.7 9530.0 4593.4 4669.8 4679.8 4686.9 4705.3 3804.1 4194.7 4276.8 4284.8 4338.6 3758.7 3768.6 3832.3 3886.0 3888.8 1230.8 1243.4 1247.5 1257.7 1262.7 823.8 827.5 827.5 839.4 855.8 5057.3 5228.6 5260.9 5272.2 5353.4 847.9 863.4 885.1 894.8 895.3 3432.1 3453.7 3470.3 3523.7 3573.3 9721.3 10003.6 10048.2 10090.4 10135.3 9839.6 10047.9 10051.3 10059.6 10076.6 7489.0 7549.0 7617.2 7658.8 7679.4 5060.5 5275.4 5354.2 5376.0 5397.1 9256.0 9379.0 9423.4 9446.5 9458.1 9489.5 9508.7 9562.4 9584.0 9630.9 5984.9 6367.9 6612.3 6638.8 6739.3 7769.8 7773.0 7802.3 7817.6 7852.0 6777.3 6991.5 7034.6 7085.3 7156.4 6802.2 6858.8 6898.5 6954.2 7068.4 6052.8 6059.7 6100.4 6123.4 6135.5 4517.8 4548.8 4588.2 4599.9 4625.2 4630.1 4673.2 4685.2 4726.7 4760.4 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | [#4] prototype.html Cycles:180 Average:4287.13 Median:4630.30 stddev:1242.35 (26.8%) stddev-sans-first:1209.27 15:51:59 INFO - PROCESS | 22392 | Values: 301.0 311.5 315.9 316.6 319.2 4949.7 5009.8 5086.2 5221.8 5229.8 5103.5 5114.7 5126.3 5126.3 5138.9 4053.8 4056.6 4058.1 4078.9 4086.3 3874.5 4005.9 4045.0 4090.9 4117.0 3934.3 3934.4 3958.3 3961.2 3965.7 3981.8 3983.8 3994.5 3997.2 4027.6 3532.4 3549.3 3551.7 3551.8 3561.2 4859.9 4976.0 4988.6 5024.8 5122.7 4546.6 4582.1 4586.2 4591.3 4628.0 5293.4 5320.6 5322.3 5337.2 5370.5 3768.3 3803.6 3858.9 3863.3 3871.7 5669.0 5673.7 5696.7 5722.0 5728.5 3966.2 3988.7 3991.9 4000.6 4003.6 3744.5 3958.5 4045.3 4093.4 4102.4 4479.9 4490.7 4505.0 4518.7 4524.4 5350.2 5359.0 5370.4 5372.5 5399.2 1297.7 1322.7 1357.3 1359.2 1371.9 850.8 851.2 871.5 877.3 882.5 4180.5 4211.0 4222.8 4236.7 4276.5 4828.1 4895.0 4899.9 4919.5 4935.7 4228.4 4238.6 4263.7 4271.9 4293.7 2522.7 2607.7 2625.4 2637.6 2644.8 4576.0 4578.4 4607.6 4624.0 4650.9 4956.7 4971.8 4972.3 5002.4 5007.4 4284.0 4573.9 4610.4 4648.4 4685.7 4818.4 4849.6 4856.4 4898.4 4937.7 4632.7 4664.3 4737.3 4784.3 4790.8 4314.7 4607.4 4616.9 4618.5 4642.7 3524.6 3533.4 3561.6 3563.3 3576.9 4862.9 4879.1 4889.2 4891.4 5298.3 5292.8 5425.6 5431.4 5437.5 5452.9 5412.9 5433.6 5434.4 5452.8 5457.5 5072.9 5302.4 5322.8 5323.2 5381.0 5413.6 5451.9 5456.6 5458.3 5485.3 5270.7 5305.5 5330.7 5336.1 5363.7 15:51:59 INFO - PROCESS | 22392 | 15:51:59 INFO - PROCESS | 22392 | [#5] yui.html Cycles:180 Average:17592.63 Median:513.91 stddev:24648.52 (4796.3%) stddev-sans-first:24684.85 15:51:59 INFO - PROCESS | 22392 | Values: 654.3 707.9 728.5 745.8 756.1 12819.6 13282.2 13522.5 13523.6 13541.1 13092.0 13427.6 13457.5 13470.8 13495.3 630.0 776.3 791.4 802.2 807.9 807.0 808.3 813.7 814.0 816.0 851.3 856.9 857.7 858.9 860.8 833.3 837.7 839.5 840.8 840.9 613.6 615.4 615.4 617.7 618.2 12936.0 12999.9 13765.7 14098.0 14104.6 13936.4 14265.2 14271.5 14286.5 14456.1 11131.2 11174.9 11182.9 11233.8 11275.6 3437.9 3468.7 3509.7 3533.8 3600.3 66674.3 67241.0 67322.3 67893.3 68964.3 11044.6 11264.3 11305.4 11326.7 11431.1 20957.3 21301.8 21348.8 21357.8 21361.8 7109.9 7176.8 7260.9 7263.0 7449.6 3708.4 3759.3 3787.5 3875.8 3935.7 3004.1 3102.3 3114.8 3120.8 3137.5 497.2 512.9 514.9 515.9 522.5 536.8 562.4 566.8 568.6 572.3 7365.4 7384.9 7499.0 7660.5 7677.3 443.1 495.3 497.3 510.0 534.5 3508.5 3690.8 3798.5 3912.0 3927.7 53197.2 78991.2 83411.0 93412.0 96822.0 80332.2 84535.0 84740.6 85191.6 85600.6 74401.3 74908.6 77214.6 77799.0 80711.0 7370.5 7563.4 7586.1 7812.1 7849.7 38761.5 43380.8 44079.0 44534.0 44637.8 55486.7 64643.0 65851.0 66691.3 68101.3 11627.8 11928.2 12027.6 12120.0 12431.7 10971.1 11202.3 12029.9 12568.4 13085.7 10989.7 10990.0 11430.7 11583.7 11669.6 10593.2 10615.9 10746.2 10867.8 11399.9 10156.5 10497.1 10543.6 10612.2 10786.8 9622.9 9759.5 9838.8 9852.3 9853.9 8462.6 8577.0 8649.5 8821.7 8949.7 15:51:59 INFO - PROCESS | 22392 | -------- Summary: end -------- 15:51:59 INFO - PROCESS | 22392 | 15:52:00 INFO - TEST-INFO | 22392: exit 0 15:52:00 INFO - TEST-OK | dromaeo_css | took 1116873ms 15:52:00 INFO - TEST-START | kraken 15:52:00 INFO - operating with platform_type : linux_ 15:52:00 INFO - Initialising browser for kraken test... 15:52:00 INFO - TEST-INFO | started process 22624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_xeC9E/profile http://localhost:35675/getInfo.html) 15:52:05 INFO - PROCESS | 22624 | __metrics Screen width/height:1600/1200 15:52:05 INFO - PROCESS | 22624 | colorDepth:24 15:52:05 INFO - PROCESS | 22624 | Browser inner width/height: 1024/697 15:52:05 INFO - PROCESS | 22624 | __metrics 15:52:06 ERROR - PROCESS | 22624 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 15:52:07 INFO - TEST-INFO | 22624: exit 0 15:52:07 INFO - Browser initialized. 15:52:07 INFO - Running cycle 1/1 for kraken test... 15:52:07 INFO - TEST-INFO | started process 22743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_xeC9E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:19 INFO - PROCESS | 22743 | RSS: Main: 186445824 15:52:19 INFO - PROCESS | 22743 | 15:52:21 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35675/tests/kraken/driver.html?testName=audio-beat-detection) 15:52:22 INFO - PROCESS | 22743 | RSS: Main: 190574592 15:52:22 INFO - PROCESS | 22743 | 15:52:25 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35675/tests/kraken/driver.html?testName=audio-dft) 15:52:26 INFO - PROCESS | 22743 | RSS: Main: 190226432 15:52:26 INFO - PROCESS | 22743 | 15:52:29 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35675/tests/kraken/driver.html?testName=audio-fft) 15:52:29 INFO - PROCESS | 22743 | RSS: Main: 186916864 15:52:29 INFO - PROCESS | 22743 | 15:52:32 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35675/tests/kraken/driver.html?testName=audio-oscillator) 15:52:32 INFO - PROCESS | 22743 | RSS: Main: 186744832 15:52:32 INFO - PROCESS | 22743 | 15:52:34 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:52:35 INFO - PROCESS | 22743 | RSS: Main: 187281408 15:52:35 INFO - PROCESS | 22743 | 15:52:38 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35675/tests/kraken/driver.html?testName=imaging-darkroom) 15:52:39 INFO - PROCESS | 22743 | RSS: Main: 187637760 15:52:39 INFO - PROCESS | 22743 | 15:52:43 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35675/tests/kraken/driver.html?testName=imaging-desaturate) 15:52:43 INFO - PROCESS | 22743 | RSS: Main: 184492032 15:52:43 INFO - PROCESS | 22743 | 15:52:46 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35675/tests/kraken/driver.html?testName=json-parse-financial) 15:52:47 INFO - PROCESS | 22743 | RSS: Main: 186019840 15:52:47 INFO - PROCESS | 22743 | 15:52:48 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:52:49 INFO - PROCESS | 22743 | RSS: Main: 186515456 15:52:49 INFO - PROCESS | 22743 | 15:52:51 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:52:52 INFO - PROCESS | 22743 | RSS: Main: 186421248 15:52:52 INFO - PROCESS | 22743 | 15:52:54 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:52:55 INFO - PROCESS | 22743 | RSS: Main: 186781696 15:52:55 INFO - PROCESS | 22743 | 15:52:57 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:52:58 INFO - PROCESS | 22743 | RSS: Main: 186970112 15:52:58 INFO - PROCESS | 22743 | 15:53:01 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:53:01 INFO - PROCESS | 22743 | RSS: Main: 186531840 15:53:01 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | Cycle 1(1): loaded http://localhost:35675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35675/tests/kraken/driver.html?testName=ai-astar) 15:53:03 INFO - PROCESS | 22743 | RSS: Main: 187142144 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | __start_tp_report 15:53:03 INFO - PROCESS | 22743 | _x_x_mozilla_page_load 15:53:03 INFO - PROCESS | 22743 | _x_x_mozilla_page_load_details 15:53:03 INFO - PROCESS | 22743 | |i|pagename|runs| 15:53:03 INFO - PROCESS | 22743 | |0;ai-astar;104;114;107;113;114;114;117;116;115;114 15:53:03 INFO - PROCESS | 22743 | |1;audio-beat-detection;158;140;155;180;134;161;154;134;189;132 15:53:03 INFO - PROCESS | 22743 | |2;audio-dft;162;162;165;167;165;163;163;167;164;162 15:53:03 INFO - PROCESS | 22743 | |3;audio-fft;76;76;76;153;78;80;77;75;159;76 15:53:03 INFO - PROCESS | 22743 | |4;audio-oscillator;87;85;134;86;85;88;138;86;88;133 15:53:03 INFO - PROCESS | 22743 | |5;imaging-gaussian-blur;118;118;117;118;117;118;119;118;118;117 15:53:03 INFO - PROCESS | 22743 | |6;imaging-darkroom;148;149;148;147;147;148;148;147;190;148 15:53:03 INFO - PROCESS | 22743 | |7;imaging-desaturate;96;95;96;95;96;96;96;96;96;96 15:53:03 INFO - PROCESS | 22743 | |8;json-parse-financial;63;64;64;65;64;64;63;64;63;66 15:53:03 INFO - PROCESS | 22743 | |9;json-stringify-tinderbox;64;64;66;66;65;66;64;65;70;64 15:53:03 INFO - PROCESS | 22743 | |10;stanford-crypto-aes;84;82;82;90;89;82;90;83;105;87 15:53:03 INFO - PROCESS | 22743 | |11;stanford-crypto-ccm;136;145;133;140;134;132;135;142;136;145 15:53:03 INFO - PROCESS | 22743 | |12;stanford-crypto-pbkdf2;166;171;172;166;169;173;165;166;174;166 15:53:03 INFO - PROCESS | 22743 | |13;stanford-crypto-sha256-iterative;68;68;71;68;67;68;69;65;72;74 15:53:03 INFO - PROCESS | 22743 | __end_tp_report 15:53:03 INFO - PROCESS | 22743 | __start_cc_report 15:53:03 INFO - PROCESS | 22743 | _x_x_mozilla_cycle_collect,953 15:53:03 INFO - PROCESS | 22743 | __end_cc_report 15:53:03 INFO - PROCESS | 22743 | __startTimestamp1467067983904__endTimestamp 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | ------- Summary: start ------- 15:53:03 INFO - PROCESS | 22743 | Number of tests: 14 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:4.08 (3.6%) stddev-sans-first:2.82 15:53:03 INFO - PROCESS | 22743 | Values: 104.0 114.0 107.0 113.0 114.0 114.0 117.0 116.0 115.0 114.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#1] audio-beat-detection Cycles:10 Average:153.70 Median:156.50 stddev:19.57 (12.5%) stddev-sans-first:20.69 15:53:03 INFO - PROCESS | 22743 | Values: 158.0 140.0 155.0 180.0 134.0 161.0 154.0 134.0 189.0 132.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.50 stddev:1.94 (1.2%) stddev-sans-first:1.92 15:53:03 INFO - PROCESS | 22743 | Values: 162.0 162.0 165.0 167.0 165.0 163.0 163.0 167.0 164.0 162.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#3] audio-fft Cycles:10 Average:92.60 Median:76.00 stddev:33.47 (44.0%) stddev-sans-first:34.96 15:53:03 INFO - PROCESS | 22743 | Values: 76.0 76.0 76.0 153.0 78.0 80.0 77.0 75.0 159.0 76.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:86.00 stddev:23.52 (27.3%) stddev-sans-first:24.39 15:53:03 INFO - PROCESS | 22743 | Values: 87.0 85.0 134.0 86.0 85.0 88.0 138.0 86.0 88.0 133.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 15:53:03 INFO - PROCESS | 22743 | Values: 118.0 118.0 117.0 118.0 117.0 118.0 119.0 118.0 118.0 117.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:148.00 stddev:13.37 (9.0%) stddev-sans-first:14.10 15:53:03 INFO - PROCESS | 22743 | Values: 148.0 149.0 148.0 147.0 147.0 148.0 148.0 147.0 190.0 148.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:53:03 INFO - PROCESS | 22743 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:0.94 (1.5%) stddev-sans-first:0.93 15:53:03 INFO - PROCESS | 22743 | Values: 63.0 64.0 64.0 65.0 64.0 64.0 63.0 64.0 63.0 66.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:1.84 (2.8%) stddev-sans-first:1.88 15:53:03 INFO - PROCESS | 22743 | Values: 64.0 64.0 66.0 66.0 65.0 66.0 64.0 65.0 70.0 64.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:85.50 stddev:7.03 (8.2%) stddev-sans-first:7.34 15:53:03 INFO - PROCESS | 22743 | Values: 84.0 82.0 82.0 90.0 89.0 82.0 90.0 83.0 105.0 87.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#11] stanford-crypto-ccm Cycles:10 Average:137.80 Median:138.00 stddev:4.85 (3.5%) stddev-sans-first:5.10 15:53:03 INFO - PROCESS | 22743 | Values: 136.0 145.0 133.0 140.0 134.0 132.0 135.0 142.0 136.0 145.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:170.00 stddev:3.43 (2.0%) stddev-sans-first:3.48 15:53:03 INFO - PROCESS | 22743 | Values: 166.0 171.0 172.0 166.0 169.0 173.0 165.0 166.0 174.0 166.0 15:53:03 INFO - PROCESS | 22743 | 15:53:03 INFO - PROCESS | 22743 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:68.50 stddev:2.62 (3.8%) stddev-sans-first:2.76 15:53:03 INFO - PROCESS | 22743 | Values: 68.0 68.0 71.0 68.0 67.0 68.0 69.0 65.0 72.0 74.0 15:53:03 INFO - PROCESS | 22743 | -------- Summary: end -------- 15:53:03 INFO - PROCESS | 22743 | 15:53:04 INFO - TEST-INFO | 22743: exit 0 15:53:04 INFO - TEST-OK | kraken | took 64364ms 15:53:04 INFO - SUITE-END | took 1181s 15:53:05 INFO - Completed test suite (00:19:41) 15:53:05 INFO - javascript benchmark 15:53:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6478.837184693274, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.564141916583, 2699.4601079784034, 2712.51174788538, 2718.1845446366087, 2722.0881004863368, 4890.24201248807, 4899.608031357492, 4920.803167873285, 4958.826441074554, 4980.750962451872, 4861.9513804861945, 4899.6325275604295, 4905.3132561441425, 4922.237053256751, 4977.402711674604, 4457.866264012085, 4474.194644963905, 4544.250198717207, 4577.130345234412, 4588.197065513538, 627.5458360298576, 4436.933445998313, 4498.392716983214, 4531.4109165808395, 4573.085382923428, 4540.954590454103, 4561.931571026437, 4564.726116433025, 4570.177368073745, 4572.5427457254345, 4536.364908912755, 4541.205289074399, 4554.95445045552, 4578.450585929677, 4603.608693261077, 4362.47650281965, 4448.822047118111, 4453.465584129893, 4455.977720111411, 4457.2199865023495, 4791.47293797682, 4828.203346447832, 4872.3422337984275, 4902.975485122587, 4963.379577552806, 5229.555487783505, 5257.053730328539, 5269.0, 5276.604254680914, 5342.332208473941, 5318.654287471302, 5357.276767636397, 5364.9731751341, 5375.43557926416, 5415.6479828811, 4950.777215025332, 4956.826511072131, 4959.08256972454, 4960.131976904027, 4965.255211718199, 5331.413544510101, 5374.059539580636, 5384.165454354581, 5446.727663616883, 5462.262594549765, 5179.352580927384, 5194.740262986911, 5205.6616319939085, 5222.4255331913455, 5241.31862857826, 5271.578273738091, 5282.603804714662, 5289.603279754034, 5309.36287645485, 5336.439673834269, 5093.904810465768, 5202.0, 5226.033183860998, 5273.973630131824, 5288.762005709752, 5243.108671525773, 5262.210668399771, 5274.287971123926, 5275.261463395128, 5292.947070529245, 4567.611753001032, 4570.7257564546235, 4575.17646823575, 4576.496585375606, 4615.538446155425, 3848.0, 3869.0134015825784, 3876.9224615507533, 3891.3384724596885, 3896.6687831534637, 4538.047010127844, 4550.089982003613, 4586.449626044896, 4619.884502887455, 4640.23436133041, 5306.389765176977, 5326.680399176062, 5356.812511562077, 5366.812161574326, 5369.5972802040005, 4626.306054091846, 4635.142498637695, 4640.907181856344, 4650.093231819763, 4696.718196908197, 4616.838410655611, 4766.213574760195, 4791.47293797689, 4791.5687588117235, 4809.663323567317, 4996.150654388763, 5011.824586139468, 5037.19404895215, 5044.621653376085, 5093.23601459784, 5375.032494150934, 5383.273258110262, 5386.192071189353, 5397.055515284731, 5398.352197736297, 4912.238603016538, 4947.529984651453, 4948.406191257072, 5008.824691135938, 5023.397192336943, 5237.973810130925, 5258.447862974485, 5265.6840589564745, 5267.446918073547, 5324.307839980777, 5189.662671926313, 5289.920651190312, 5326.121190003606, 5328.60035497347, 5330.760115794876, 5130.2817605536, 5175.404828444777, 5190.2214667800135, 5193.61047921415, 5206.453322401093, 5140.30605868203, 5158.871028224325, 5184.300119483822, 5204.8178313758835, 5223.608229382736, 5022.723750193774, 5030.346055012825, 5056.418511871182, 5088.211327244283, 5108.0, 5157.613179011514, 5180.119379705384, 5197.168453047494, 5224.7126408047925, 5247.790088396421, 5070.2394640802695, 5071.54356107952, 5076.847694569169, 5078.238264260391, 5132.12753831842], "value": 4830.078928712226, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.0894137898057, 578.2511647416595, 583.9737211825468, 596.1177457363102, 607.565590602719, 33135.171620709465, 37367.43948840766, 37428.25143497129, 37463.0, 37550.43674344887, 38305.42541861867, 39022.21955560887, 39123.0, 39163.21673566527, 39209.41185882214, 7633.6183190840375, 7869.252421020023, 7882.014748156481, 7885.408594355417, 7888.921110788905, 7640.388768898475, 7661.6169191540475, 7740.535567865946, 7744.457887947818, 7762.30139287464, 7850.921490785105, 7896.763097107096, 7906.367490600767, 7914.643841027168, 7918.287354138127, 7736.883946740803, 7827.647755850972, 7836.804079897991, 7865.331446827026, 7875.724349647763, 7745.031871016123, 7792.2986931176465, 7815.491993020435, 7817.0, 7829.099653539859, 36646.26707465862, 36740.63259367399, 36812.0, 36900.446493302625, 36938.630613693786, 3046.2384403899027, 3095.77716801862, 3096.349766549005, 3181.681831816823, 3361.159710072482, 29445.852770736223, 29490.11529654114, 29904.8504757477, 30370.54444183339, 30415.23961900948, 3293.5059741038795, 3308.8180150091735, 3324.1855745342305, 3333.599968003831, 3369.5956485221686, 208138.0, 267026.0, 268137.0, 268799.0, 269930.0, 54691.179632305546, 55425.722871385384, 55426.16860747092, 55450.16824747632, 55627.16559251615, 32627.836860815543, 33412.66587334096, 33634.49548256778, 35287.82356088254, 35419.82290088585, 13338.73322533544, 13404.0, 13460.192388456724, 13480.86519134796, 13561.660958475919, 10705.518251678694, 10788.352698837938, 10832.78334433307, 10840.349579025284, 10874.130069594414, 17872.374466893598, 17924.372646957294, 17972.460826175236, 17977.191026403583, 18066.1870215838, 6827.624480653512, 6866.277796579833, 7072.7524536642, 7098.609576473344, 7163.641817909083, 816.3795515408333, 825.2160447574828, 831.9958400208081, 907.4950162158909, 949.8812648418948, 28325.0, 28671.283217919303, 28714.569281460794, 28997.710022899922, 29248.415031699245, 993.7416271769392, 997.2271489595621, 1010.3584224017802, 1015.3146626027402, 1021.8518314844455, 15037.924810375878, 15176.241187940559, 15343.0, 15533.45632902843, 15574.143422111665, 385044.0, 455073.0, 463867.0, 464425.0, 465344.0, 49688.0, 49819.50180498149, 50222.497775022515, 50310.24534631983, 50381.74809125931, 1167.4279602994472, 1235.5304984105983, 1238.7708273969345, 1250.1811313589612, 1261.0227074017894, 26802.865985669945, 28341.43317133687, 28706.282342941595, 28854.855725721238, 29116.56325155082, 41238.38142427926, 41371.379429307985, 41610.37584436296, 41947.58052419437, 42122.0, 292376.0, 292978.0, 293603.0, 294121.0, 296450.0, 36811.63188368082, 36899.0, 37419.43870841994, 38146.0, 39830.0, 40098.39852402275, 40384.79807600943, 41330.0, 41421.514709117546, 41573.1685366297, 35258.29483410369, 35307.82346088253, 35412.291754165286, 35537.466937995436, 35676.0, 34632.48051279182, 34720.652793471745, 34852.0, 35035.64964350426, 35066.64933350736, 34098.48852267169, 34231.82884085563, 36886.44670330001, 37661.246775063795, 40119.3982090263, 32907.341853162325, 33787.83106084454, 35053.29893402169, 35132.297354053284, 36930.0, 26043.218703438928, 26084.86957565276, 26242.868785655948, 26420.603690945034, 26705.397676139495], "value": 17520.652661971315, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3132283582462, 377.14012052520246, 380.4901432081012, 393.0841140143466, 394.5896267881403, 15564.252902864622, 16861.241244144014, 17290.56773580661, 17670.028148451845, 18088.638227235446, 16656.916715416435, 17052.23264953077, 17218.655626887456, 17391.82608173921, 17526.12369381529, 5127.641065125443, 5313.0, 5335.493128152819, 5361.061814182522, 5364.409914909357, 3788.90527736806, 5927.555433342495, 6277.905831412532, 6282.434580887719, 6400.743970241208, 6237.500999920018, 6313.842153946165, 6342.619442833422, 6348.396902294275, 6408.455281301095, 6040.184575082351, 6138.785142519991, 6160.630362178239, 6168.8766224675255, 6218.782342618009, 5245.423003469616, 5370.865728356823, 5497.367802702701, 5528.585356098269, 5547.945890280874, 20773.480662983395, 20803.0, 20847.061882215337, 20893.8955305224, 21208.25771098004, 1972.80271972803, 1991.5519008223034, 2048.7502623399714, 2466.4080620651093, 2540.2379286214245, 13170.485394179601, 13518.93240533791, 13567.52513662027, 13695.931520342434, 13942.512012079529, 1826.242109524559, 1876.8686191966567, 1885.1045753267226, 1892.082340065081, 1924.9903750481299, 59296.40703592952, 61773.382266177214, 65423.672881635284, 66626.66686666584, 68068.31931680719, 9837.163841073589, 10027.749306267402, 10248.590056397661, 10366.222533309881, 10461.110805581611, 13950.0, 14064.0, 14090.506832260822, 14093.577192684235, 14095.0, 7104.467164962649, 7149.857002859913, 7201.3518783309755, 7334.229905859808, 7358.889616655754, 6708.798736037927, 6709.496787740939, 6733.696983635748, 6885.414739747077, 6907.033015377851, 4018.8948039288844, 4326.740395576276, 4422.955770442319, 4462.509123996358, 4511.436070491189, 1726.6028813372996, 1814.8185181481747, 1852.6016906364928, 1901.153986476005, 1910.510300793125, 850.8298340331835, 852.8933883264592, 883.9071897450804, 918.9356745027784, 922.5479515037583, 16325.183740812912, 16749.413770517975, 16838.410655626994, 16921.576960576083, 16921.576960576083, 867.9869801952975, 882.5013867165031, 884.7434244069148, 892.5537231384308, 917.738444543302, 8810.911890881009, 9428.764280893032, 9457.479838608942, 9460.0, 9498.762530936781, 399726.0, 400109.0, 400164.0, 400433.0, 402108.0, 49172.0, 53002.469975300526, 78248.0, 78586.0, 78900.6054969733, 372.599455585247, 386.5516001438318, 388.5046565628846, 389.09923527034823, 390.0229924040261, 9258.53707314618, 9581.856272155785, 9701.854472182782, 10025.047620476043, 10079.89920100819, 27025.0, 35250.64749352474, 35446.64553354433, 36908.81545592255, 38072.42891356577, 49526.0, 50159.74920125376, 52737.47262527326, 53402.73298633482, 53442.73278633582, 7237.276272372721, 7469.477136600386, 7505.924940750522, 7572.053493313336, 7714.5756983366455, 10673.786524269626, 11183.608573699881, 11327.263727857662, 11634.767304653691, 12174.391280435766, 9679.9032009679, 9887.703368898945, 10062.547185376528, 10166.847497287694, 10288.434136122285, 9778.16885564735, 9857.852132217879, 9896.208303335572, 10372.792544148924, 10459.529321180717, 9361.344705870524, 9422.199113075465, 9437.292203084658, 9489.715308540479, 9621.0, 7675.923240767521, 7680.423968202519, 7692.499987500794, 7720.266574675396, 7890.605469726605, 8498.957505212435, 9727.562259698188, 10053.246006549392, 10280.434576098087, 10339.121174699934], "value": 8062.604923723019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.9584512464623, 1413.201541129262, 1489.3744627256551, 1639.598298416888, 1697.134461424673, 6904.378605925465, 7703.0, 7749.92250077499, 8221.342292616584, 8235.547044912535, 7723.884141737878, 8012.078610959741, 8083.272505474506, 8099.109097999215, 8118.431709780318, 2196.5057861981045, 2212.5021870079263, 2225.0, 2226.855254408458, 2232.151782322715, 2137.9037943292587, 2209.5139069404786, 2210.1159536185573, 2216.556688662266, 2223.499712564676, 2178.9019494122804, 2197.6923230747707, 2205.5368372641765, 2210.933671989843, 2213.911443542256, 2160.2007257314817, 2206.8124209442217, 2218.589560931217, 2234.55308938213, 2241.932742017742, 2179.5096103376773, 2216.966745498819, 2223.8888055597154, 2225.799678028969, 2229.8885055747146, 8012.8798068028445, 8016.759497215035, 8057.0, 8065.475744076616, 8068.878966815502, 4382.715123516993, 4504.09918016398, 4529.886752831173, 4549.499555048942, 4551.476580193287, 2835.5915630674617, 2952.483315419793, 2979.702029797025, 2980.806247593921, 2998.34036511967, 1006.5772375602264, 1053.6733612580124, 1061.7186445591924, 1068.8984546468073, 1069.2408390043079, 11194.44027798615, 11194.496247668874, 11239.494222759995, 11242.0, 11267.26762760418, 4342.1532801103485, 4381.452318460192, 4398.3842262083335, 4399.494058183286, 4454.621357184611, 9306.0, 9393.436393816393, 9436.197923176469, 9529.666461673809, 9530.046995300414, 4593.356930029799, 4669.789859456333, 4679.836205732783, 4686.882827929261, 4705.270683044134, 3804.0680033391995, 4194.6853985951175, 4276.764777937244, 4284.828606855691, 4338.58783415575, 3758.7180961428003, 3768.5854555998817, 3832.271868345061, 3886.0, 3888.8444462221196, 1230.8215308780177, 1243.3783108445778, 1247.5009996001672, 1257.7484503099417, 1262.665393670678, 823.8352329534117, 827.5076329583887, 827.5159031966367, 839.4291881520625, 855.8373908957278, 5057.31726216956, 5228.581713462914, 5260.868478288074, 5272.182811664198, 5353.411124776271, 847.9067302596709, 863.3869952333788, 885.0750965241339, 894.8389289927876, 895.3329769321766, 3432.1248081738995, 3453.6546345365264, 3470.3232869590693, 3523.700485458765, 3573.338932297533, 9721.270904682176, 10003.649872254437, 10048.246381521416, 10090.445025523666, 10135.34120282194, 9839.60641574329, 10047.899521004696, 10051.296409251283, 10059.597616095421, 10076.59693612262, 7488.999999999891, 7549.0, 7617.200193979553, 7658.770236892902, 7679.424043196783, 5060.544550990428, 5275.366955965309, 5354.196870469461, 5375.973120134374, 5397.136458166675, 9256.0, 9378.968313485648, 9423.434593924387, 9446.480443575394, 9458.148766610762, 9489.478078705737, 9508.667196648084, 9562.426254424758, 9584.041595840361, 9630.855537167088, 5984.850378740392, 6367.87264254703, 6612.272650008592, 6638.767643132467, 6739.258681545124, 7769.844603108019, 7773.0, 7802.258785415376, 7817.648205830865, 7852.0, 6777.2883847195335, 6991.475639327173, 7034.648267586703, 7085.291470852873, 7156.427485801228, 6802.217744959393, 6858.828529286808, 6898.517103802686, 6954.200266969363, 7068.434525238072, 6052.757889684363, 6059.666718330358, 6100.389961004042, 6123.418275263843, 6135.509159267337, 4517.774111294488, 4548.795304211252, 4588.242939914877, 4599.862004139881, 4625.236835922035, 4630.143423466669, 4673.158831410379, 4685.203515402458, 4726.716397016053, 4760.428748550196], "value": 4238.073910760336, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.9939801203976, 311.5000424318968, 315.879965613067, 316.55365934033017, 319.17811635039766, 4949.727764972925, 5009.7996080156845, 5086.160783470727, 5221.817236396727, 5229.764660590278, 5103.515166059227, 5114.718690472023, 5126.256692779554, 5126.282320475128, 5138.922916156261, 4053.7973101344887, 4056.574059723731, 4058.147788964321, 4078.938815917764, 4086.346184610463, 3874.51494129673, 4005.9399109013243, 4045.0, 4090.9181816363653, 4116.958830411688, 3934.3114954882926, 3934.390169523714, 3958.2875082485143, 3961.227560625679, 3965.702572307074, 3981.820818063194, 3983.800809959505, 3994.500687414073, 3997.2205419943125, 4027.597240275978, 3532.3994920863515, 3549.3078849624335, 3551.698105660996, 3551.786892786415, 3561.1809283864595, 4859.878503037417, 4976.0, 4988.551030407244, 5024.799008039674, 5122.692638441668, 4546.568076032771, 4582.060677561092, 4586.243269860503, 4591.265397536378, 4627.953720462786, 5293.364796224478, 5320.600954928396, 5322.33470816148, 5337.226102215157, 5370.516653501204, 3768.340540405418, 3803.573659877546, 3858.903527411809, 3863.28529222092, 3871.728978971473, 5668.951244019829, 5673.6879471629045, 5696.6866822324755, 5722.0, 5728.455796699307, 3966.2265858157384, 3988.66096381805, 3991.9201615967518, 4000.6399424051974, 4003.579624139482, 3744.513213282256, 3958.525921299188, 4045.3122969094734, 4093.385992101149, 4102.384642303678, 4479.932801007987, 4490.708103973231, 4505.040307466263, 4518.661100417482, 4524.366588677588, 5350.2242174885205, 5358.97320513395, 5370.355557333145, 5372.5433338166695, 5399.190121481809, 1297.6820678933723, 1322.6693326668333, 1357.3281225793296, 1359.2184493916038, 1371.8628137186402, 850.7575341027778, 851.1531026628544, 871.4509858789049, 877.3244601656688, 882.5278476015301, 4180.498340199135, 4210.9683127633925, 4222.767747773902, 4236.703430759879, 4276.481848943642, 4828.082664293773, 4894.972055868237, 4899.877503062381, 4919.458859525449, 4935.654504184673, 4228.365745138254, 4238.6263692332395, 4263.658907287409, 4271.850485233002, 4293.724326107168, 2522.6846644169477, 2607.674040744907, 2625.4092829113297, 2637.591173368131, 2644.7884169266413, 4576.0, 4578.35179335439, 4607.585317321457, 4623.976880115578, 4650.883727906829, 4956.677815941952, 4971.751412429364, 4972.290101894476, 5002.449730529639, 5007.399112106497, 4283.957160428356, 4573.931391029138, 4610.377599024089, 4648.372469716613, 4685.742284174266, 4818.4217893852965, 4849.612031037438, 4856.417229932431, 4898.436679781871, 4937.679050861682, 4632.652551058752, 4664.250905910284, 4737.313089601919, 4784.258439941815, 4790.7844147012775, 4314.734764248795, 4607.40103786519, 4616.8614941551805, 4618.468876079294, 4642.744649044335, 3524.559430071241, 3533.363994481018, 3561.590417101963, 3563.3051554946537, 3576.9432576268227, 4862.92705609409, 4879.146149423766, 4889.242167464049, 4891.437484689306, 5298.284731561344, 5292.814751483679, 5425.647332923348, 5431.429699881455, 5437.510624043697, 5452.890942181213, 5412.944475827176, 5433.592480563894, 5434.424017035016, 5452.754626041917, 5457.508824205841, 5072.949270507248, 5302.443243459381, 5322.787088516571, 5323.228131920772, 5380.973095134499, 5413.646588352912, 5451.890962180655, 5456.563474922076, 5458.317710286215, 5485.341758988947, 5270.679471932658, 5305.522502974751, 5330.733463326896, 5336.119540275811, 5363.704996225089], "value": 3894.264854280272, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.3097032630576, 707.8938159276108, 728.4936968806679, 745.7874505765858, 756.121098763344, 12819.615411537645, 13282.218401375521, 13522.526711565097, 13523.59429217125, 13541.119827211236, 13092.0, 13427.597172084854, 13457.528986485475, 13470.797938030937, 13495.325233738324, 629.9874002519947, 776.2974508070206, 791.4420333664767, 802.1697543042923, 807.9192080791933, 807.0, 808.2725547007682, 813.6704634622987, 814.0353680888167, 816.0166998766501, 851.3317046118798, 856.8500512410303, 857.7255278310943, 858.935579831512, 860.7503823891064, 833.3250067445358, 837.7068026190815, 839.5046922315832, 840.7855996720858, 840.9075001749802, 613.6103574230395, 615.3600255734032, 615.3600255734032, 617.7405489694339, 618.1870839845589, 12936.0, 12999.935000325033, 13765.724685506333, 14098.0, 14104.647383815383, 13936.372863221079, 14265.215413152273, 14271.500497482539, 14286.499972500913, 14456.132632042007, 11131.165162612755, 11174.888251117465, 11182.944085279603, 11233.775324493465, 11275.605353812578, 3437.8796742113905, 3468.6704763047724, 3509.736769742254, 3533.840977156034, 3600.3339382214376, 66674.33325666729, 67240.99138512975, 67322.32677673259, 67893.3210667897, 68964.31035689628, 11044.558217671203, 11264.324140551593, 11305.378204198683, 11326.660200193843, 11431.14266430021, 20957.26649567258, 21301.786982130292, 21348.78651213499, 21357.78642213558, 21361.78638213598, 7109.928900710926, 7176.7846964591145, 7260.891086633705, 7263.0, 7449.590272534954, 3708.369577171887, 3759.3045286622064, 3787.4886890270013, 3875.803104424755, 3935.7244992850797, 3004.0987703688807, 3102.348506813594, 3114.7819652624553, 3120.8127512349333, 3137.450946084426, 497.2466712929914, 512.9051125541787, 514.9150390185653, 515.9226116082617, 522.4566450891031, 536.7906516458506, 562.3869981719945, 566.8412844403491, 568.556525909791, 572.2589246925241, 7365.447591430664, 7384.852302954018, 7498.962505187439, 7660.502067365604, 7677.270659287359, 443.08502941425854, 495.2719502331567, 497.3385397421493, 509.997450012755, 534.5429657642737, 3508.5438892943753, 3690.797006164633, 3798.506194194737, 3912.0, 3927.66614837742, 53197.2020419694, 78991.21008789954, 83411.0, 93412.0, 96822.0, 80332.19667803364, 84535.0, 84740.57629711812, 85191.57404212939, 85600.57199713962, 74401.25598744051, 74908.62545687346, 77214.61392693, 77799.0, 80711.0, 7370.520916140434, 7563.357114645097, 7586.051743532059, 7812.140664527011, 7849.6655568554625, 38761.51146396838, 43380.78309608432, 44079.0, 44534.0, 44637.776811115735, 55486.72256638691, 64643.0, 65851.0, 66691.33308666851, 68101.31898681149, 11627.76744465089, 11928.165028447926, 12027.579034734092, 12120.0, 12431.689207769878, 10971.122310215324, 11202.327860328407, 12029.879701202875, 12568.434420451285, 13085.672858178621, 10989.670309890396, 10990.0, 11430.714232144262, 11583.6524904253, 11669.649910502698, 10593.152547796004, 10615.946920265349, 10746.24776265654, 10867.836982445111, 11399.886001139883, 10156.542955566869, 10497.107745841384, 10543.630972916219, 10612.151027917902, 10786.838197427202, 9622.855657165008, 9759.463229522444, 9838.75403114928, 9852.310338276251, 9853.852192217266, 8462.619182136696, 8576.957115214384, 8649.4810311379, 8821.74686286012, 8949.686760963335], "value": 6566.9243209910865, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 107.0, 113.0, 114.0, 114.0, 117.0, 116.0, 115.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 140.0, 155.0, 180.0, 134.0, 161.0, 154.0, 134.0, 189.0, 132.0], "value": 153.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 165.0, 167.0, 165.0, 163.0, 163.0, 167.0, 164.0, 162.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 153.0, 78.0, 80.0, 77.0, 75.0, 159.0, 76.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 134.0, 86.0, 85.0, 88.0, 138.0, 86.0, 88.0, 133.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 118.0, 119.0, 118.0, 118.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 147.0, 147.0, 148.0, 148.0, 147.0, 190.0, 148.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 65.0, 64.0, 64.0, 63.0, 64.0, 63.0, 66.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 66.0, 65.0, 66.0, 64.0, 65.0, 70.0, 64.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 90.0, 89.0, 82.0, 90.0, 83.0, 105.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 145.0, 133.0, 140.0, 134.0, 132.0, 135.0, 142.0, 136.0, 145.0], "value": 137.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 172.0, 166.0, 169.0, 173.0, 165.0, 166.0, 174.0, 166.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 68.0, 67.0, 68.0, 69.0, 65.0, 72.0, 74.0], "value": 69.0, "unit": "score"}]}]} 15:53:05 INFO - Return code: 0 15:53:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:53:05 ERROR - # TBPL SUCCESS # 15:53:05 INFO - Running post-action listener: _resource_record_post_action 15:53:05 INFO - ##### 15:53:05 INFO - ##### Finished run-tests step (success) 15:53:05 INFO - ##### 15:53:05 INFO - Running post-run listener: _resource_record_post_run 15:53:05 INFO - Total resource usage - Wall time: 1199s; CPU: 14.0%; Read bytes: 4096; Write bytes: 563097600; Read time: 8; Write time: 546616 15:53:05 INFO - TinderboxPrint: CPU usage
13.6% 15:53:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:53:05 INFO - TinderboxPrint: I/O write bytes / time
563,097,600 / 546,616 15:53:05 INFO - TinderboxPrint: CPU idle
8,265.2 (86.4%) 15:53:05 INFO - TinderboxPrint: CPU user
1,239.7 (13.0%) 15:53:05 INFO - TinderboxPrint: Swap in / out
0 / 0 15:53:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246444032; Read time: 0; Write time: 296768 15:53:05 INFO - run-tests - Wall time: 1183s; CPU: 14.0%; Read bytes: 4096; Write bytes: 300482560; Read time: 8; Write time: 244152 15:53:05 INFO - Running post-run listener: _upload_blobber_files 15:53:05 INFO - Blob upload gear active. 15:53:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:53:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:53:05 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:53:05 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:53:06 INFO - (blobuploader) - INFO - Open directory for files ... 15:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:07 INFO - (blobuploader) - INFO - Done attempting. 15:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:07 INFO - (blobuploader) - INFO - Done attempting. 15:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:53:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:11 INFO - (blobuploader) - INFO - Done attempting. 15:53:11 INFO - (blobuploader) - INFO - Iteration through files over. 15:53:11 INFO - Return code: 0 15:53:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:53:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:53:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/839bc62d4ba3b628a2cc8463d3653272c88ac464eb8f088676c108be726725e8d955ffa8481437a4ba4f03d86d6235cd48f22a0083d3993ad63b2577004c3573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6dd7ac065fc59493d2335ebe3bed982c6a86e4fccc5571b4236a7ee62fad708b05517c405d62fa6f558dc1cce350b9f68d0762878866061204f64e93cb780187", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92c4addd5a246691fd0147d4ec9cb3d6f91446f42133cb4be9de091605da05a2ad9f6651c96b1cc0727161a4c5af087daa20af1c6babb5232f45254d259b17a7"} 15:53:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:53:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:53:11 INFO - Contents: 15:53:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/839bc62d4ba3b628a2cc8463d3653272c88ac464eb8f088676c108be726725e8d955ffa8481437a4ba4f03d86d6235cd48f22a0083d3993ad63b2577004c3573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6dd7ac065fc59493d2335ebe3bed982c6a86e4fccc5571b4236a7ee62fad708b05517c405d62fa6f558dc1cce350b9f68d0762878866061204f64e93cb780187", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92c4addd5a246691fd0147d4ec9cb3d6f91446f42133cb4be9de091605da05a2ad9f6651c96b1cc0727161a4c5af087daa20af1c6babb5232f45254d259b17a7"} 15:53:11 INFO - Running post-run listener: copy_logs_to_upload_dir 15:53:11 INFO - Copying logs to upload dir... 15:53:11 INFO - mkdir: /builds/slave/test/build/upload/logs 15:53:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.784702 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-27 15:53:11.622118) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 15:53:11.627768) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/839bc62d4ba3b628a2cc8463d3653272c88ac464eb8f088676c108be726725e8d955ffa8481437a4ba4f03d86d6235cd48f22a0083d3993ad63b2577004c3573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6dd7ac065fc59493d2335ebe3bed982c6a86e4fccc5571b4236a7ee62fad708b05517c405d62fa6f558dc1cce350b9f68d0762878866061204f64e93cb780187", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92c4addd5a246691fd0147d4ec9cb3d6f91446f42133cb4be9de091605da05a2ad9f6651c96b1cc0727161a4c5af087daa20af1c6babb5232f45254d259b17a7"} build_url:https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/b19aXiuZR16YTFEG2rG8JQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/839bc62d4ba3b628a2cc8463d3653272c88ac464eb8f088676c108be726725e8d955ffa8481437a4ba4f03d86d6235cd48f22a0083d3993ad63b2577004c3573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6dd7ac065fc59493d2335ebe3bed982c6a86e4fccc5571b4236a7ee62fad708b05517c405d62fa6f558dc1cce350b9f68d0762878866061204f64e93cb780187", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/92c4addd5a246691fd0147d4ec9cb3d6f91446f42133cb4be9de091605da05a2ad9f6651c96b1cc0727161a4c5af087daa20af1c6babb5232f45254d259b17a7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 15:53:11.656098) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:53:11.656743) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467066750.46756-537709857 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 15:53:11.740074) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 15:53:11.740409) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 15:53:11.740702) ========= ========= Total master_lag: 0.18 =========