builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-054 starttime: 1465104039.36 results: success (0) buildid: 20160604193055 builduid: c8b20be8f91f4c3d9502481262476f3d revision: 4f5df0a09686af630e023c17e965977d9aa1c38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.361779) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.362688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.363011) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.381382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.381699) ========= 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=cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-04 22:20:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.9M=0s 2016-06-04 22:20:40 (34.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082385 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.478297) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.478666) ========= 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=cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014694 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.528342) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:39.528762) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4f5df0a09686af630e023c17e965977d9aa1c38a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4f5df0a09686af630e023c17e965977d9aa1c38a --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=cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-04 22:20:40,324 truncating revision to first 12 chars 2016-06-04 22:20:40,324 Setting DEBUG logging. 2016-06-04 22:20:40,324 attempt 1/10 2016-06-04 22:20:40,324 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/4f5df0a09686?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 22:20:40,637 unpacking tar archive at: mozilla-inbound-4f5df0a09686/testing/mozharness/ program finished with exit code 0 elapsedTime=0.674004 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:40.217435) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:40.217817) ========= script_repo_revision: 4f5df0a09686af630e023c17e965977d9aa1c38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:40.218287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:40.218661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 22:20:40.228918) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-06-04 22:20:40.229243) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:20:41 INFO - MultiFileLogger online at 20160604 22:20:41 in /builds/slave/test 22:20:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:20:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:20:41 INFO - {'append_to_log': False, 22:20:41 INFO - 'base_work_dir': '/builds/slave/test', 22:20:41 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:20:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:20:41 INFO - 'branch': 'Mozilla-Inbound', 22:20:41 INFO - 'buildbot_json_path': 'buildprops.json', 22:20:41 INFO - 'config_files': ('talos/linux_config.py',), 22:20:41 INFO - 'default_actions': ('clobber', 22:20:41 INFO - 'read-buildbot-config', 22:20:41 INFO - 'download-and-extract', 22:20:41 INFO - 'populate-webroot', 22:20:41 INFO - 'create-virtualenv', 22:20:41 INFO - 'install', 22:20:41 INFO - 'run-tests'), 22:20:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:20:41 INFO - 'download_minidump_stackwalk': True, 22:20:41 INFO - 'download_symbols': 'ondemand', 22:20:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:20:41 INFO - 'tooltool.py': '/tools/tooltool.py', 22:20:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:20:41 INFO - '/tools/misc-python/virtualenv.py')}, 22:20:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:20:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:20:41 INFO - 'installer_path': 'installer.exe', 22:20:41 INFO - 'log_level': 'info', 22:20:41 INFO - 'log_name': 'talos', 22:20:41 INFO - 'log_to_console': True, 22:20:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:20:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:20:41 INFO - 'opt_config_files': (), 22:20:41 INFO - 'pip_index': False, 22:20:41 INFO - 'sps_profile': False, 22:20:41 INFO - 'sps_profile_interval': 0, 22:20:41 INFO - 'suite': 'dromaeojs-e10s', 22:20:41 INFO - 'system_bits': '32', 22:20:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:20:41 INFO - 'title': 'talos-linux64-ix-054', 22:20:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:20:41 INFO - 'use_talos_json': True, 22:20:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:20:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:20:41 INFO - 'work_dir': 'build'} 22:20:41 INFO - ##### 22:20:41 INFO - ##### Running clobber step. 22:20:41 INFO - ##### 22:20:41 INFO - Running pre-action listener: _resource_record_pre_action 22:20:41 INFO - Running main action method: clobber 22:20:41 INFO - rmtree: /builds/slave/test/build 22:20:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:20:42 INFO - Running post-action listener: _resource_record_post_action 22:20:42 INFO - ##### 22:20:42 INFO - ##### Finished clobber step (success) 22:20:42 INFO - ##### 22:20:42 INFO - ##### 22:20:42 INFO - ##### Running read-buildbot-config step. 22:20:42 INFO - ##### 22:20:42 INFO - Running pre-action listener: _resource_record_pre_action 22:20:42 INFO - Running main action method: read_buildbot_config 22:20:42 INFO - Using buildbot properties: 22:20:42 INFO - { 22:20:42 INFO - "project": "", 22:20:42 INFO - "product": "firefox", 22:20:42 INFO - "script_repo_revision": "production", 22:20:42 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:20:42 INFO - "repository": "", 22:20:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:20:42 INFO - "buildid": "20160604193055", 22:20:42 INFO - "pgo_build": "True", 22:20:42 INFO - "basedir": "/builds/slave/test", 22:20:42 INFO - "buildnumber": 798, 22:20:42 INFO - "slavename": "talos-linux64-ix-054", 22:20:42 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:20:42 INFO - "platform": "ubuntu64_hw", 22:20:42 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:20:42 INFO - "branch": "mozilla-inbound", 22:20:42 INFO - "repo_path": "integration/mozilla-inbound", 22:20:42 INFO - "revision": "4f5df0a09686af630e023c17e965977d9aa1c38a", 22:20:42 INFO - "stage_platform": "linux64-pgo", 22:20:42 INFO - "builduid": "c8b20be8f91f4c3d9502481262476f3d", 22:20:42 INFO - "slavebuilddir": "test" 22:20:42 INFO - } 22:20:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:20:42 INFO - Running post-action listener: _resource_record_post_action 22:20:42 INFO - ##### 22:20:42 INFO - ##### Finished read-buildbot-config step (success) 22:20:42 INFO - ##### 22:20:42 INFO - ##### 22:20:42 INFO - ##### Running download-and-extract step. 22:20:42 INFO - ##### 22:20:42 INFO - Running pre-action listener: _resource_record_pre_action 22:20:42 INFO - Running main action method: download_and_extract 22:20:42 INFO - mkdir: /builds/slave/test/build/tests 22:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:42 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:20:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:20:47 INFO - Downloaded 1361 bytes. 22:20:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:20:47 INFO - Using the following test package requirements: 22:20:47 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:20:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:20:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:20:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'jsshell-linux-x86_64.zip'], 22:20:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:20:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:20:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:20:47 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:20:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:20:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:20:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:20:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:20: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')]} 22:20:47 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:20:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:20:52 INFO - Downloaded 24441808 bytes. 22:20:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:20:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:20:53 INFO - Return code: 0 22:20:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:53 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:20:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:20:59 INFO - Downloaded 24441808 bytes. 22:20:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:20:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:21:01 INFO - Return code: 0 22:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:01 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:21:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:21:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:21:02 INFO - Downloaded 10833369 bytes. 22:21:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:21:02 INFO - Return code: 0 22:21: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')]} 22:21:02 INFO - https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:21:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:21:04 INFO - Downloaded 59183030 bytes. 22:21:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:04 INFO - mkdir: /builds/slave/test/properties 22:21:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:21:04 INFO - Writing to file /builds/slave/test/properties/build_url 22:21:04 INFO - Contents: 22:21:04 INFO - build_url:https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:21:05 INFO - Running post-action listener: _resource_record_post_action 22:21:05 INFO - Running post-action listener: set_extra_try_arguments 22:21:05 INFO - ##### 22:21:05 INFO - ##### Finished download-and-extract step (success) 22:21:05 INFO - ##### 22:21:05 INFO - ##### 22:21:05 INFO - ##### Running populate-webroot step. 22:21:05 INFO - ##### 22:21:05 INFO - Running pre-action listener: _resource_record_pre_action 22:21:05 INFO - Running main action method: populate_webroot 22:21:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:21:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:21:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:21:05 INFO - '--tppagecycles', 22:21:05 INFO - '7'], 22:21:05 INFO - 'tests': ['tsvgm']}}, 22:21:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:21:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:21:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:21:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:21:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['damp', 'tps']}, 22:21:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'tests': ['damp', 'tps']}, 22:21:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['dromaeo_dom']}, 22:21:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:21:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['a11yr', 22:21:05 INFO - 'ts_paint', 22:21:05 INFO - 'tpaint', 22:21:05 INFO - 'sessionrestore', 22:21:05 INFO - 'sessionrestore_no_auto_restore', 22:21:05 INFO - 'tabpaint']}, 22:21:05 INFO - 'other-e10s': {'tests': ['a11yr', 22:21:05 INFO - 'ts_paint', 22:21:05 INFO - 'tpaint', 22:21:05 INFO - 'sessionrestore', 22:21:05 INFO - 'sessionrestore_no_auto_restore', 22:21:05 INFO - 'tabpaint']}, 22:21:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:21:05 INFO - 'ts_paint', 22:21:05 INFO - 'tpaint', 22:21:05 INFO - 'sessionrestore', 22:21:05 INFO - 'sessionrestore_no_auto_restore', 22:21:05 INFO - 'tabpaint']}, 22:21:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:21:05 INFO - 'ts_paint', 22:21:05 INFO - 'tpaint', 22:21:05 INFO - 'sessionrestore', 22:21:05 INFO - 'sessionrestore_no_auto_restore', 22:21:05 INFO - 'tabpaint']}, 22:21:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['a11yr', 22:21:05 INFO - 'ts_paint', 22:21:05 INFO - 'tpaint', 22:21:05 INFO - 'sessionrestore', 22:21:05 INFO - 'sessionrestore_no_auto_restore', 22:21:05 INFO - 'tabpaint']}, 22:21:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['a11yr', 22:21:05 INFO - 'ts_paint', 22:21:05 INFO - 'tpaint', 22:21:05 INFO - 'sessionrestore', 22:21:05 INFO - 'sessionrestore_no_auto_restore', 22:21:05 INFO - 'tabpaint']}, 22:21:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['tsvgx', 22:21:05 INFO - 'tsvgr_opacity', 22:21:05 INFO - 'tart', 22:21:05 INFO - 'tscrollx', 22:21:05 INFO - 'cart']}, 22:21:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:21:05 INFO - 'tsvgr_opacity', 22:21:05 INFO - 'tart', 22:21:05 INFO - 'tscrollx', 22:21:05 INFO - 'cart']}, 22:21:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'talos_options': ['--disable-e10s'], 22:21:05 INFO - 'tests': ['tp5o']}, 22:21:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'tests': ['tp5o']}, 22:21:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'talos_options': ['--disable-e10s', 22:21:05 INFO - '--xperf_path', 22:21:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:05 INFO - 'tests': ['tp5n']}, 22:21:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:05 INFO - 'talos_options': ['--xperf_path', 22:21:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:05 INFO - 'tests': ['tp5n']}}, 22:21:05 INFO - 'talos.zip': {'path': '', 22:21:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:21:05 INFO - Running post-action listener: _resource_record_post_action 22:21:05 INFO - ##### 22:21:05 INFO - ##### Finished populate-webroot step (success) 22:21:05 INFO - ##### 22:21:05 INFO - ##### 22:21:05 INFO - ##### Running create-virtualenv step. 22:21:05 INFO - ##### 22:21:05 INFO - Running pre-action listener: _resource_record_pre_action 22:21:05 INFO - Running main action method: create_virtualenv 22:21:05 INFO - Creating virtualenv /builds/slave/test/build/venv 22:21:05 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 22:21:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:21:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:21:05 INFO - Using real prefix '/usr' 22:21:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:21:05 INFO - Installing distribute.............................................................................................................................................................................................done. 22:21:07 INFO - Installing pip.................done. 22:21:07 INFO - Return code: 0 22:21:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:07 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:07 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 22:21:07 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 22:21:07 INFO - Using env: {'DISPLAY': ':0', 22:21:07 INFO - 'HOME': '/home/cltbld', 22:21:07 INFO - 'LANG': 'en_US.UTF-8', 22:21:07 INFO - 'LANGUAGE': 'en_US:en', 22:21:07 INFO - 'LOGNAME': 'cltbld', 22:21:07 INFO - 'MAIL': '/var/mail/cltbld', 22:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:07 INFO - 'MOZ_NO_REMOTE': '1', 22:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:07 INFO - 'NO_EM_RESTART': '1', 22:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:07 INFO - 'PWD': '/builds/slave/test', 22:21:07 INFO - 'SHELL': '/bin/bash', 22:21:07 INFO - 'SHLVL': '1', 22:21:07 INFO - 'TERM': 'linux', 22:21:07 INFO - 'TMOUT': '86400', 22:21:07 INFO - 'USER': 'cltbld', 22:21:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:07 INFO - '_': '/tools/buildbot/bin/python'} 22:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:07 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 22:21:07 INFO - Cleaning up... 22:21:07 INFO - Return code: 0 22:21:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:07 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:07 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 22:21:07 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 22:21:07 INFO - Using env: {'DISPLAY': ':0', 22:21:07 INFO - 'HOME': '/home/cltbld', 22:21:07 INFO - 'LANG': 'en_US.UTF-8', 22:21:07 INFO - 'LANGUAGE': 'en_US:en', 22:21:07 INFO - 'LOGNAME': 'cltbld', 22:21:07 INFO - 'MAIL': '/var/mail/cltbld', 22:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:07 INFO - 'MOZ_NO_REMOTE': '1', 22:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:07 INFO - 'NO_EM_RESTART': '1', 22:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:07 INFO - 'PWD': '/builds/slave/test', 22:21:07 INFO - 'SHELL': '/bin/bash', 22:21:07 INFO - 'SHLVL': '1', 22:21:07 INFO - 'TERM': 'linux', 22:21:07 INFO - 'TMOUT': '86400', 22:21:07 INFO - 'USER': 'cltbld', 22:21:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:07 INFO - '_': '/tools/buildbot/bin/python'} 22:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:08 INFO - Downloading/unpacking psutil>=3.1.1 22:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:10 INFO - Installing collected packages: psutil 22:21:10 INFO - Running setup.py install for psutil 22:21:10 INFO - building 'psutil._psutil_linux' extension 22:21:10 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 22:21:10 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 22:21:10 INFO - building 'psutil._psutil_posix' extension 22:21:10 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 22:21:10 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 22:21:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:10 INFO - Successfully installed psutil 22:21:10 INFO - Cleaning up... 22:21:10 INFO - Return code: 0 22:21:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:21:10 INFO - Using env: {'DISPLAY': ':0', 22:21:10 INFO - 'HOME': '/home/cltbld', 22:21:10 INFO - 'LANG': 'en_US.UTF-8', 22:21:10 INFO - 'LANGUAGE': 'en_US:en', 22:21:10 INFO - 'LOGNAME': 'cltbld', 22:21:10 INFO - 'MAIL': '/var/mail/cltbld', 22:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:10 INFO - 'MOZ_NO_REMOTE': '1', 22:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:10 INFO - 'NO_EM_RESTART': '1', 22:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:10 INFO - 'PWD': '/builds/slave/test', 22:21:10 INFO - 'SHELL': '/bin/bash', 22:21:10 INFO - 'SHLVL': '1', 22:21:10 INFO - 'TERM': 'linux', 22:21:10 INFO - 'TMOUT': '86400', 22:21:10 INFO - 'USER': 'cltbld', 22:21:10 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:10 INFO - '_': '/tools/buildbot/bin/python'} 22:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:12 INFO - Installing collected packages: mozsystemmonitor 22:21:12 INFO - Running setup.py install for mozsystemmonitor 22:21:12 INFO - Successfully installed mozsystemmonitor 22:21:12 INFO - Cleaning up... 22:21:12 INFO - Return code: 0 22:21:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:12 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:12 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 22:21:12 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 22:21:12 INFO - Using env: {'DISPLAY': ':0', 22:21:12 INFO - 'HOME': '/home/cltbld', 22:21:12 INFO - 'LANG': 'en_US.UTF-8', 22:21:13 INFO - 'LANGUAGE': 'en_US:en', 22:21:13 INFO - 'LOGNAME': 'cltbld', 22:21:13 INFO - 'MAIL': '/var/mail/cltbld', 22:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:13 INFO - 'MOZ_NO_REMOTE': '1', 22:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:13 INFO - 'NO_EM_RESTART': '1', 22:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:13 INFO - 'PWD': '/builds/slave/test', 22:21:13 INFO - 'SHELL': '/bin/bash', 22:21:13 INFO - 'SHLVL': '1', 22:21:13 INFO - 'TERM': 'linux', 22:21:13 INFO - 'TMOUT': '86400', 22:21:13 INFO - 'USER': 'cltbld', 22:21:13 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:13 INFO - '_': '/tools/buildbot/bin/python'} 22:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:13 INFO - Downloading/unpacking jsonschema==2.5.1 22:21:13 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 22:21:13 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 22:21:13 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 22:21:13 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 22:21:13 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 22:21:13 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 22:21:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:15 INFO - warning: no files found matching '*.txt' 22:21:15 INFO - no previously-included directories found matching 'build' 22:21:15 INFO - no previously-included directories found matching 'dist' 22:21:15 INFO - no previously-included directories found matching '.git*' 22:21:15 INFO - Installing collected packages: jsonschema, functools32 22:21:15 INFO - Running setup.py install for functools32 22:21:15 INFO - warning: no files found matching '*.txt' 22:21:15 INFO - no previously-included directories found matching 'build' 22:21:15 INFO - no previously-included directories found matching 'dist' 22:21:15 INFO - no previously-included directories found matching '.git*' 22:21:15 INFO - Successfully installed jsonschema functools32 22:21:15 INFO - Cleaning up... 22:21:15 INFO - Return code: 0 22:21:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21:15 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')]} 22:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:15 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')]} 22:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:15 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:15 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 22:21:15 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 22:21:15 INFO - Using env: {'DISPLAY': ':0', 22:21:15 INFO - 'HOME': '/home/cltbld', 22:21:15 INFO - 'LANG': 'en_US.UTF-8', 22:21:15 INFO - 'LANGUAGE': 'en_US:en', 22:21:15 INFO - 'LOGNAME': 'cltbld', 22:21:15 INFO - 'MAIL': '/var/mail/cltbld', 22:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:15 INFO - 'MOZ_NO_REMOTE': '1', 22:21:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:15 INFO - 'NO_EM_RESTART': '1', 22:21:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:15 INFO - 'PWD': '/builds/slave/test', 22:21:15 INFO - 'SHELL': '/bin/bash', 22:21:15 INFO - 'SHLVL': '1', 22:21:15 INFO - 'TERM': 'linux', 22:21:15 INFO - 'TMOUT': '86400', 22:21:15 INFO - 'USER': 'cltbld', 22:21:15 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:15 INFO - '_': '/tools/buildbot/bin/python'} 22:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:15 INFO - Downloading/unpacking blobuploader==1.2.4 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 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 22:21:15 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 22:21:17 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:17 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 22:21:17 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 22:21:17 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 22:21:17 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 22:21:17 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 22:21:17 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 22:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 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 22:21:18 INFO - Downloading docopt-0.6.1.tar.gz 22:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:18 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:18 INFO - Running setup.py install for blobuploader 22:21:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:18 INFO - Running setup.py install for requests 22:21:18 INFO - Running setup.py install for docopt 22:21:18 INFO - Successfully installed blobuploader requests docopt 22:21:18 INFO - Cleaning up... 22:21:19 INFO - Return code: 0 22:21:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:19 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')]} 22:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:19 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')]} 22:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:19 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:19 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 22:21:19 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 22:21:19 INFO - Using env: {'DISPLAY': ':0', 22:21:19 INFO - 'HOME': '/home/cltbld', 22:21:19 INFO - 'LANG': 'en_US.UTF-8', 22:21:19 INFO - 'LANGUAGE': 'en_US:en', 22:21:19 INFO - 'LOGNAME': 'cltbld', 22:21:19 INFO - 'MAIL': '/var/mail/cltbld', 22:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:19 INFO - 'MOZ_NO_REMOTE': '1', 22:21:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:19 INFO - 'NO_EM_RESTART': '1', 22:21:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:19 INFO - 'PWD': '/builds/slave/test', 22:21:19 INFO - 'SHELL': '/bin/bash', 22:21:19 INFO - 'SHLVL': '1', 22:21:19 INFO - 'TERM': 'linux', 22:21:19 INFO - 'TMOUT': '86400', 22:21:19 INFO - 'USER': 'cltbld', 22:21:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:19 INFO - '_': '/tools/buildbot/bin/python'} 22:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:19 INFO - Running setup.py (path:/tmp/pip-dqQ5oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:19 INFO - Running setup.py (path:/tmp/pip-oME5sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:19 INFO - Running setup.py (path:/tmp/pip-RVxpZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:19 INFO - Running setup.py (path:/tmp/pip-tMUaKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:19 INFO - Running setup.py (path:/tmp/pip-Se0ixr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:19 INFO - Running setup.py (path:/tmp/pip-854dkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:19 INFO - Running setup.py (path:/tmp/pip-DRzhvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:19 INFO - Running setup.py (path:/tmp/pip-4tGY2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:19 INFO - Running setup.py (path:/tmp/pip-sK7bvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:19 INFO - Running setup.py (path:/tmp/pip-GFCCWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:19 INFO - Running setup.py (path:/tmp/pip-P4Zpc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:20 INFO - Running setup.py (path:/tmp/pip-wxqBuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:20 INFO - Running setup.py (path:/tmp/pip-ufr3aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:20 INFO - Running setup.py (path:/tmp/pip-b2s0BR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:20 INFO - Running setup.py (path:/tmp/pip-hb6zPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:20 INFO - Running setup.py (path:/tmp/pip-O_aYop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:20 INFO - Running setup.py (path:/tmp/pip-YvbNtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:20 INFO - Running setup.py install for manifestparser 22:21:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:20 INFO - Running setup.py install for mozcrash 22:21:20 INFO - Running setup.py install for mozdebug 22:21:20 INFO - Running setup.py install for mozdevice 22:21:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:20 INFO - Running setup.py install for mozfile 22:21:21 INFO - Running setup.py install for mozhttpd 22:21:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozinfo 22:21:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozInstall 22:21:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozleak 22:21:21 INFO - Running setup.py install for mozlog 22:21:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for moznetwork 22:21:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozprocess 22:21:21 INFO - Running setup.py install for mozprofile 22:21:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozrunner 22:21:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozscreenshot 22:21:22 INFO - Running setup.py install for moztest 22:21:22 INFO - Running setup.py install for mozversion 22:21:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:22 INFO - Cleaning up... 22:21:22 INFO - Return code: 0 22:21:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21: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')]} 22:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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')]} 22:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:22 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:22 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 22:21:22 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 22:21:22 INFO - Using env: {'DISPLAY': ':0', 22:21:22 INFO - 'HOME': '/home/cltbld', 22:21:22 INFO - 'LANG': 'en_US.UTF-8', 22:21:22 INFO - 'LANGUAGE': 'en_US:en', 22:21:22 INFO - 'LOGNAME': 'cltbld', 22:21:22 INFO - 'MAIL': '/var/mail/cltbld', 22:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:22 INFO - 'MOZ_NO_REMOTE': '1', 22:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:22 INFO - 'NO_EM_RESTART': '1', 22:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:22 INFO - 'PWD': '/builds/slave/test', 22:21:22 INFO - 'SHELL': '/bin/bash', 22:21:22 INFO - 'SHLVL': '1', 22:21:22 INFO - 'TERM': 'linux', 22:21:22 INFO - 'TMOUT': '86400', 22:21:22 INFO - 'USER': 'cltbld', 22:21:22 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:22 INFO - '_': '/tools/buildbot/bin/python'} 22:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:22 INFO - Running setup.py (path:/tmp/pip-VXprNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:22 INFO - Running setup.py (path:/tmp/pip-azf2hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:22 INFO - Running setup.py (path:/tmp/pip-dcmEX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:22 INFO - Running setup.py (path:/tmp/pip-SoXvvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:22 INFO - Running setup.py (path:/tmp/pip-Kx_YE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:22 INFO - Running setup.py (path:/tmp/pip-NnMY9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:22 INFO - Running setup.py (path:/tmp/pip-JHTPZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:22 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)) 22:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:22 INFO - Running setup.py (path:/tmp/pip-cfOp3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:23 INFO - Running setup.py (path:/tmp/pip-OGsI37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:23 INFO - Running setup.py (path:/tmp/pip-qE0PVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:23 INFO - Running setup.py (path:/tmp/pip-VWIxWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:23 INFO - Running setup.py (path:/tmp/pip-ExiC_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:23 INFO - Running setup.py (path:/tmp/pip-kUuehC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:23 INFO - Running setup.py (path:/tmp/pip-YqjTCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:23 INFO - Running setup.py (path:/tmp/pip-uK6bkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:23 INFO - Running setup.py (path:/tmp/pip-DYMQZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:23 INFO - Running setup.py (path:/tmp/pip-D59TJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:23 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)) 22:21:23 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)) 22:21:23 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)) 22:21:23 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)) 22:21:23 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)) 22:21:23 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)) 22:21:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:23 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 22:21:23 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 22:21:23 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 22:21:23 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 22:21:23 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 22:21:23 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 22:21:25 INFO - Downloading blessings-1.6.tar.gz 22:21:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:21:25 INFO - Installing collected packages: blessings 22:21:25 INFO - Running setup.py install for blessings 22:21:25 INFO - Successfully installed blessings 22:21:25 INFO - Cleaning up... 22:21:25 INFO - Return code: 0 22:21:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:21:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:26 INFO - Reading from file tmpfile_stdout 22:21:26 INFO - Current package versions: 22:21:26 INFO - argparse == 1.2.1 22:21:26 INFO - blessings == 1.6 22:21:26 INFO - blobuploader == 1.2.4 22:21:26 INFO - docopt == 0.6.1 22:21:26 INFO - functools32 == 3.2.3-2 22:21:26 INFO - jsonschema == 2.5.1 22:21:26 INFO - manifestparser == 1.1 22:21:26 INFO - mozInstall == 1.12 22:21:26 INFO - mozcrash == 0.17 22:21:26 INFO - mozdebug == 0.1 22:21:26 INFO - mozdevice == 0.48 22:21:26 INFO - mozfile == 1.2 22:21:26 INFO - mozhttpd == 0.7 22:21:26 INFO - mozinfo == 0.9 22:21:26 INFO - mozleak == 0.1 22:21:26 INFO - mozlog == 3.2 22:21:26 INFO - moznetwork == 0.27 22:21:26 INFO - mozprocess == 0.22 22:21:26 INFO - mozprofile == 0.28 22:21:26 INFO - mozrunner == 6.11 22:21:26 INFO - mozscreenshot == 0.1 22:21:26 INFO - mozsystemmonitor == 0.3 22:21:26 INFO - moztest == 0.7 22:21:26 INFO - mozversion == 1.4 22:21:26 INFO - psutil == 3.1.1 22:21:26 INFO - requests == 1.2.3 22:21:26 INFO - wsgiref == 0.1.2 22:21:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:26 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 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:26 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 22:21:26 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 22:21:26 INFO - Using env: {'DISPLAY': ':0', 22:21:26 INFO - 'HOME': '/home/cltbld', 22:21:26 INFO - 'LANG': 'en_US.UTF-8', 22:21:26 INFO - 'LANGUAGE': 'en_US:en', 22:21:26 INFO - 'LOGNAME': 'cltbld', 22:21:26 INFO - 'MAIL': '/var/mail/cltbld', 22:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:26 INFO - 'MOZ_NO_REMOTE': '1', 22:21:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:26 INFO - 'NO_EM_RESTART': '1', 22:21:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:26 INFO - 'PWD': '/builds/slave/test', 22:21:26 INFO - 'SHELL': '/bin/bash', 22:21:26 INFO - 'SHLVL': '1', 22:21:26 INFO - 'TERM': 'linux', 22:21:26 INFO - 'TMOUT': '86400', 22:21:26 INFO - 'USER': 'cltbld', 22:21:26 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:26 INFO - '_': '/tools/buildbot/bin/python'} 22:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 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)) 22:21:26 INFO - Cleaning up... 22:21:26 INFO - Return code: 0 22:21:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:21:26 INFO - Using env: {'DISPLAY': ':0', 22:21:26 INFO - 'HOME': '/home/cltbld', 22:21:26 INFO - 'LANG': 'en_US.UTF-8', 22:21:26 INFO - 'LANGUAGE': 'en_US:en', 22:21:26 INFO - 'LOGNAME': 'cltbld', 22:21:26 INFO - 'MAIL': '/var/mail/cltbld', 22:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:26 INFO - 'MOZ_NO_REMOTE': '1', 22:21:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:26 INFO - 'NO_EM_RESTART': '1', 22:21:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:26 INFO - 'PWD': '/builds/slave/test', 22:21:26 INFO - 'SHELL': '/bin/bash', 22:21:26 INFO - 'SHLVL': '1', 22:21:26 INFO - 'TERM': 'linux', 22:21:26 INFO - 'TMOUT': '86400', 22:21:26 INFO - 'USER': 'cltbld', 22:21:26 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:26 INFO - '_': '/tools/buildbot/bin/python'} 22:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:21:26 INFO - Cleaning up... 22:21:26 INFO - Return code: 0 22:21:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b5a96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2377350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, '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': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', '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 22:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:21:26 INFO - Using env: {'DISPLAY': ':0', 22:21:26 INFO - 'HOME': '/home/cltbld', 22:21:26 INFO - 'LANG': 'en_US.UTF-8', 22:21:26 INFO - 'LANGUAGE': 'en_US:en', 22:21:26 INFO - 'LOGNAME': 'cltbld', 22:21:26 INFO - 'MAIL': '/var/mail/cltbld', 22:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:26 INFO - 'MOZ_NO_REMOTE': '1', 22:21:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:26 INFO - 'NO_EM_RESTART': '1', 22:21:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:26 INFO - 'PWD': '/builds/slave/test', 22:21:26 INFO - 'SHELL': '/bin/bash', 22:21:26 INFO - 'SHLVL': '1', 22:21:26 INFO - 'TERM': 'linux', 22:21:26 INFO - 'TMOUT': '86400', 22:21:26 INFO - 'USER': 'cltbld', 22:21:26 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:26 INFO - '_': '/tools/buildbot/bin/python'} 22:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:26 INFO - Downloading/unpacking flake8 22:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:21:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:21:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:21:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:21:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:21:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:21:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:21:29 INFO - no previously-included directories found matching 'docs/_build' 22:21:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:21:29 INFO - Running setup.py install for pep8 22:21:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:21:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:21:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:21:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:21:29 INFO - no previously-included directories found matching 'docs/_build' 22:21:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:21:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:21:29 INFO - Cleaning up... 22:21:29 INFO - Return code: 0 22:21:29 INFO - Running post-action listener: _resource_record_post_action 22:21:29 INFO - Running post-action listener: _start_resource_monitoring 22:21:29 INFO - Starting resource monitoring. 22:21:29 INFO - ##### 22:21:29 INFO - ##### Finished create-virtualenv step (success) 22:21:29 INFO - ##### 22:21:29 INFO - ##### 22:21:29 INFO - ##### Running install step. 22:21:29 INFO - ##### 22:21:29 INFO - Running pre-action listener: _resource_record_pre_action 22:21:29 INFO - Running main action method: install 22:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:29 INFO - Reading from file tmpfile_stdout 22:21:29 INFO - Detecting whether we're running mozinstall >=1.0... 22:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:21:29 INFO - Reading from file tmpfile_stdout 22:21:29 INFO - Output received: 22:21:29 INFO - Usage: mozinstall [options] installer 22:21:29 INFO - Options: 22:21:29 INFO - -h, --help show this help message and exit 22:21:29 INFO - -d DEST, --destination=DEST 22:21:29 INFO - Directory to install application into. [default: 22:21:29 INFO - "/builds/slave/test"] 22:21:29 INFO - --app=APP Application being installed. [default: firefox] 22:21:29 INFO - mkdir: /builds/slave/test/build/application 22:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:21:46 INFO - Reading from file tmpfile_stdout 22:21:46 INFO - Output received: 22:21:46 INFO - /builds/slave/test/build/application/firefox/firefox 22:21:46 INFO - Running post-action listener: _resource_record_post_action 22:21:46 INFO - ##### 22:21:46 INFO - ##### Finished install step (success) 22:21:46 INFO - ##### 22:21:46 INFO - ##### 22:21:46 INFO - ##### Running run-tests step. 22:21:46 INFO - ##### 22:21:46 INFO - Running pre-action listener: _resource_record_pre_action 22:21:46 INFO - Running main action method: run_tests 22:21:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:21:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:21:46 INFO - Python 2.7.3 22:21:46 INFO - Return code: 0 22:21:46 INFO - grabbing minidump binary from tooltool 22:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:46 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 0x24061c0>, '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 0x24070c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2407550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:21:46 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 22:21:46 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 22:21:46 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 22:21:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:21:46 INFO - Return code: 0 22:21:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:21:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:21:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:21:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:21:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:21:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:21:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:21:47 INFO - Return code: 0 22:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 22:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 22:21:47 INFO - Using env: {'DISPLAY': ':0', 22:21:47 INFO - 'HOME': '/home/cltbld', 22:21:47 INFO - 'LANG': 'en_US.UTF-8', 22:21:47 INFO - 'LANGUAGE': 'en_US:en', 22:21:47 INFO - 'LOGNAME': 'cltbld', 22:21:47 INFO - 'MAIL': '/var/mail/cltbld', 22:21:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:21:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:47 INFO - 'MOZ_NO_REMOTE': '1', 22:21:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:21:47 INFO - 'NO_EM_RESTART': '1', 22:21:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:47 INFO - 'PWD': '/builds/slave/test', 22:21:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:21:47 INFO - 'SHELL': '/bin/bash', 22:21:47 INFO - 'SHLVL': '1', 22:21:47 INFO - 'TERM': 'linux', 22:21:47 INFO - 'TMOUT': '86400', 22:21:47 INFO - 'USER': 'cltbld', 22:21:47 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762', 22:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:47 INFO - '_': '/tools/buildbot/bin/python'} 22:21:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 22:21:47 INFO - mozversion application_buildid: 20160604193055 22:21:47 INFO - mozversion application_changeset: 4f5df0a09686af630e023c17e965977d9aa1c38a 22:21:47 INFO - mozversion application_display_name: Nightly 22:21:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:21:47 INFO - mozversion application_name: Firefox 22:21:47 INFO - mozversion application_remotingname: firefox 22:21:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:21:47 INFO - mozversion application_vendor: Mozilla 22:21:47 INFO - mozversion application_version: 49.0a1 22:21:47 INFO - mozversion platform_buildid: 20160604193055 22:21:47 INFO - mozversion platform_changeset: 4f5df0a09686af630e023c17e965977d9aa1c38a 22:21:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:21:47 INFO - mozversion platform_version: 49.0a1 22:21:47 INFO - using testdate: 1465104107 22:21:47 INFO - actual date: 1465104107 22:21:47 INFO - starting webserver on 'localhost:44393' 22:21:47 INFO - SUITE-START | Running 2 tests 22:21:47 INFO - TEST-START | dromaeo_css 22:21:47 INFO - operating with platform_type : linux_ 22:21:47 INFO - Initialising browser for dromaeo_css test... 22:21:47 INFO - TEST-INFO | started process 12164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOWfbeE/profile http://localhost:44393/getInfo.html) 22:21:52 INFO - PROCESS | 12164 | __metrics Screen width/height:1600/1200 22:21:52 INFO - PROCESS | 12164 | colorDepth:24 22:21:52 INFO - PROCESS | 12164 | Browser inner width/height: 1024/697 22:21:52 INFO - PROCESS | 12164 | __metrics 22:21:53 ERROR - PROCESS | 12164 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 22:21:54 INFO - TEST-INFO | 12164: exit 0 22:21:54 INFO - Browser initialized. 22:21:54 INFO - Running cycle 1/1 for dromaeo_css test... 22:21:54 INFO - TEST-INFO | started process 12283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOWfbeE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:06 INFO - PROCESS | 12283 | RSS: Main: 167088128 22:22:06 INFO - PROCESS | 12283 | 22:22:55 INFO - PROCESS | 12283 | 1465104175863 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] 22:25:03 INFO - PROCESS | 12283 | Cycle 1(1): loaded http://localhost:44393/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44393/tests/dromaeo/cssquery-ext.html) 22:25:04 INFO - PROCESS | 12283 | RSS: Main: 171614208 22:25:04 INFO - PROCESS | 12283 | 22:28:06 INFO - PROCESS | 12283 | Cycle 1(1): loaded http://localhost:44393/tests/dromaeo/cssquery-ext.html (next: http://localhost:44393/tests/dromaeo/cssquery-jquery.html) 22:28:07 INFO - PROCESS | 12283 | RSS: Main: 170524672 22:28:07 INFO - PROCESS | 12283 | 22:31:09 INFO - PROCESS | 12283 | Cycle 1(1): loaded http://localhost:44393/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44393/tests/dromaeo/cssquery-mootools.html) 22:31:09 INFO - PROCESS | 12283 | RSS: Main: 172306432 22:31:09 INFO - PROCESS | 12283 | 22:34:11 INFO - PROCESS | 12283 | Cycle 1(1): loaded http://localhost:44393/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44393/tests/dromaeo/cssquery-prototype.html) 22:34:12 INFO - PROCESS | 12283 | RSS: Main: 168988672 22:34:12 INFO - PROCESS | 12283 | 22:37:14 INFO - PROCESS | 12283 | Cycle 1(1): loaded http://localhost:44393/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44393/tests/dromaeo/cssquery-yui.html) 22:37:15 INFO - PROCESS | 12283 | RSS: Main: 171155456 22:37:15 INFO - PROCESS | 12283 | 22:40:17 INFO - PROCESS | 12283 | Cycle 1(1): loaded http://localhost:44393/tests/dromaeo/cssquery-yui.html (next: http://localhost:44393/tests/dromaeo/cssquery-dojo.html) 22:40:18 INFO - PROCESS | 12283 | RSS: Main: 172208128 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | __start_tp_report 22:40:18 INFO - PROCESS | 12283 | _x_x_mozilla_page_load 22:40:18 INFO - PROCESS | 12283 | _x_x_mozilla_page_load_details 22:40:18 INFO - PROCESS | 12283 | |i|pagename|runs| 22:40:18 INFO - PROCESS | 12283 | |0;dojo.html;3130.6869313068696;3135.686431356864;3163.794353367031;3169.6988786065344;3175.491921292594;5422.078246698061;5452.727363631822;5509.972450137754;5526.088195447746;5542.196381524676;5221.24291977664;5346.812861549846;5360.517553420192;5413.864653383657;5512.366077901042;4805.543473370033;4842.249451335048;4932.432770231416;4970.776315065813;5013.573846223076;875.8579405176988;4776.450708168571;4851.733154676492;4915.2872833439305;5013.097642424362;4931.3835770528685;4961.801527938878;4970.030843985424;4980.078685443204;4984.252362145671;4985.626078044161;4990.201567749179;4991.650584459089;4999.250112483121;5015.39815222172;4811.615070794328;4823.903521929542;4863.100326439585;4868.829590964299;4876.122297986397;5251.317328747276;5270.868228294324;5276.023935571931;5314.229436731653;5319.787208511655;5534.972325138348;5712.314522257314;5720.2849643794525;5724.971375143139;5730.283714535683;5762.394948530427;5791.391903850077;5817.243758311435;5824.854378640525;5877.177195192677;5385.703786291752;5398.460153984609;5411.053065713484;5420.728963551806;5430.185472179205;5731.226284451545;5797.246357973437;5805.622634528742;5931.7923872665115;5963.940360596425;5617.915731264035;5665.348484924287;5671.943280567224;5728.484436400744;5749.223854779635;5819.476247137778;5819.5053420459735;5821.126830975303;5828.59199856006;5836.533077353802;5606.607537472338;5760.74076666551;5812.476877081083;5817.476427121579;5825.94174058254;5711.742971566289;5712.971435142797;5752.712364381764;5753.942460575425;5759.107338362561;4398.3402489626815;4399.6040356368085;4401.713888597231;4408.294672852392;4413.205622987893;3857.0935830079907;3881.2819628368843;3932.4297976793778;3948.565657777642;3985.6412922837085;5406.08096623575;5459.344878614592;5472.151816468454;5475.288212532345;5475.835724928179;5692.316921969307;5757.280339957505;5787.884242315215;5789.913151302734;5806.622569532967;5262.631615786935;5379.569634429236;5420.647657902224;5436;5470.370907345627;4901.240307752304;4927.384076990376;4942.061008408391;4944.1100601891285;4952.925706114411;5897.911531326948;5900.4099590041515;5903.793367232126;5916.23088998436;5916.911246331309;5605.607607467438;5686.744096515584;5713.428657134254;5723.914141287967;5773.278340207474;5888.411158884077;5911.379305172895;5916.792912248051;5925.585209035326;5927.8221653350465;5571.916421253603;5597.3003374578175;5633.549316054788;5634.6337488063145;5661.348944871393;5728.684922329312;5776.046952252831;5814.389489103583;5833.066709326487;5839.795607153729;5711.485966263056;5718.228039214653;5723.971380143073;5737.426257374396;5738.167965644875;5454.372747134131;5598.580106491948;5688.488036076773;5691.4877661010705;5707.857303567444;5425.918611220756;5460.453954604508;5526.834194974157;5540.833774986757;5657.179708942098;5629.874025194896;5639.577031722719;5654.943450565442;5667.4615911488345;5672.064109421899;5382.138857782736;5529.585281104014;5534;5537.335519737658;5558.805441809679 22:40:18 INFO - PROCESS | 12283 | |1;ext.html;575.5798267264896;607.4017093163234;621.4407033669694;626.4518546272009;635.4439865118022;43200.35199472006;44756.552434475605;44785.55214447855;45018.09963800722;48082.75958620202;43371.78314108433;44155.55844441555;44305.556944430544;44454.33318500225;44486;8683.218510334053;8827.249683776887;8894.644214231423;8900.955495222548;8902.332325075613;8672.089430609789;8686.087960764124;8709.738707838775;8720.651173953034;8725.781855453633;8708.21626053655;8742.737717868442;8743.825123497527;8750.124987501262;8788.560571971408;8722.38943273968;8737.213650771397;8746.169113934166;8761.386702930797;8762.518061506615;8669.869951950726;8693.652253909837;8706.825863482694;8712.782180445476;8756.737297881074;41314;41620.583794161976;41636;41848.79075604633;41883;3394.2871996880685;3419;3422.9144271393166;3456.5333679953137;3476.9826150869085;31037.22406939822;31429.214269643213;31519.42402879847;33257.501137482956;33262.33475330504;4166;4167;4198.223328684173;4306.849260275875;4342.891427714301;301872;302209;302473;303861;304779;55823.88352232933;56934;56973.715131424906;57027;57447.42552574504;36215.81892090523;37019;37036;59811;60399;17579.472615821545;17732.11339433023;17743.201555930013;17761.200745966464;17842.10789460522;13192.340382980812;13199.40602672882;13207.273599952096;13214.73570528584;13227;19142.521436963907;19226.80773192278;19360.4191874244;19398;19578.314758983368;7270.963645181846;7341.155767086747;7367.852642947111;7400.888986665204;7444;999.7050869993369;1083.4474418046852;1107.197281970581;1145.7422080032093;1227.6071657069651;32048.198795030312;32793.50809737856;32938.50592241118;33003.34983250828;33483;1099.6536091131427;1154.1805318223987;1186.2882270637722;1201.9519219231308;1242.031215651793;17137.400190993256;17192.65614687724;17249.310027599007;17295.827041729422;17525.64948701019;583416;585325;588466;588602;590843;39201.21597568033;58769;59797.701011495534;59925;59947.100793488135;1356.7083077138464;1394.6304229379223;1411.7317709635136;1423.1745587559192;1425.7932599772976;31064.534031989086;31988;32229.838850805594;32258.35483290368;32648;50749.492505075956;51102.23346649729;51200;51680.74159629178;51695.22457163221;352521;353999;355282;355399;355692;26628.866855665598;26955.326116847235;26982.055628052924;27103.59344609793;28620;46111.53888461207;48760;49762;49877.25184122168;50079.49920500749;40562.594374057066;42335.576644233166;42343;42440.57559424366;42840.57159428366;41826.58173418227;41873.37189942093;42340.576594234895;42347.3647895288;42968;37158.8142059288;41205.587944120176;41637;42197.367039495046;42548.149037019706;35122.297554049284;37779.81110094432;37896.24207515779;37959.62040379671;38148;28424.49183189924;28526.144215673565;28578.71421285844;28859.278518037216;28915.566266505597 22:40:18 INFO - PROCESS | 12283 | |2;jquery.html;389.87913746738514;391.6318660459168;400.8436709683224;401.3618346828542;418.77176938568476;13604.251766152858;18007;18622.90688546559;19478.026098695063;19588.804111958878;17784.377546785865;18125.728114078265;18579.25682972683;18755.15601797919;18801.341953031646;5642.181883626871;5673.319201695792;5898.439648233411;5906.911396329058;5928.703564821763;5319.9202011969655;5629.60592758507;5813.796517121901;5820.563457740686;5860.97069514654;5643.915341269884;5647.519960803316;5649.9152512712135;5655.604107712462;5666.914996275059;7056.329648683367;7470.3650189733935;7475.700971961142;7541.924580754232;7572.621368931532;6956.408705260059;7200.351968322876;7257.165425976027;7262.673179706926;7328.157261914895;24074.518509629885;24420;24839.379015524577;25188.244352669448;25240.242792716246;2343.2970108967243;2414.2998530426157;2486.76375744304;2553.19574334086;2751.339678477171;15345.386184552604;15447;15522.689546209012;15731.44939927097;15979.360825566964;2083.812456878873;2154.138344662132;2210;2216.8115710164657;2233.9888300558555;61980.69009654968;92638.53680731621;99549;100727.49636251845;100871;16847.494575162764;17578.86896579303;17756.20097095634;17938.641227175645;18091;31630;31697;31970;32174.678253217167;32219;9565.091316324939;9643.27675424346;10215.131713804261;10288.074073333435;10460.110890574235;8663.523506207219;8683.782905427288;8750.343724220582;8934;8950;8578.898169948403;8783.58451425863;8926.38481415028;8966.955165224132;8975.596098175598;3458.239187378773;3627.220147668264;3657.23198128396;3694.741368104261;3711.870084547028;985.4776968206862;1005.3163848583034;1007.9193664506822;1032.9793404131876;1052.4001319247955;17873.374431894823;18144.546386340447;18466.722999155023;18489.7226541602;18610.348637797884;1033.8087453821067;1044.5873879817582;1056.2553399853239;1115.8382034604938;1141.634676903383;9966;10337.328073675188;10355.792884142274;10387.896121038844;10489.47552622381;465501;473235;505089;512123;513819;54936;78799.60600196962;87847.56076219706;91905;98365.99999999857;979.2557656181211;1016.2022812092472;1036.7926414716935;1039.1063685230695;1041.5677493840124;16696.248668810174;17127.486175414757;17198.570035749206;17281.56796080108;17771.911140444216;40830.183396332504;42474;42722.213356737615;42960.57039429565;43012.569874300854;58667;59387.40612593992;60559;60604.69697651484;61461.692691538046;8536.951131728465;8545.102764209669;8590.441621294596;8723.78190545216;8738.868916966123;9875.802483950138;12951.093423460266;13144.0799144059;13191.340432978504;13278;5438.945610544002;6436.871262574816;11489.425528723363;11606.88393116058;11870;11004.229703920648;11156.386398748147;11174.608888688857;11182.776344473228;11225;11560.537578496765;11625.534978600761;11630;11749.823752643888;11817.881821182022;8588.969323680949;8624.827503450022;8696.043435221995;8755.91244087551;8895.777605559913;8631;10626.681199563716;11367.943160284476;11542.94228528852;11559.19085664029 22:40:18 INFO - PROCESS | 12283 | |3;mootools.html;1472.558232530241;1498.0487390507028;1665.4087798831415;1817.3194538056368;1831.331563979148;7722.806929826743;8576.91423085769;8632.525211113387;9168.954155229223;9180.173784359415;8741.125887411255;8883.200511953923;8904.51025193614;8926.41978271412;8988.370814043019;2321.4892723600783;2323.175272778165;2336.6378211377264;2339.2163625185585;2355.4346956730346;2226.7661895500983;2253.6957510736006;2270.0465804362125;2282.2012295696454;2288.942776430586;2304.2050492580033;2310.4635417447375;2329.4642232286583;2347.3427440316655;2352.7882490575844;2228.387193521778;2258.7854153855355;2260.0959616153505;2270.4437412833795;2274.8748818814875;2209.845310828243;2216.4569680428235;2223.1663126327626;2237.5301186750803;2255.537614788964;8919.24186444153;8993.730188094367;9016.8647470288;9028.232600228988;9048.366614337;5162.45794191612;5242;5251.658642188246;5285.07511185541;5293.9735301323635;3035.48396772547;3130.3739252149435;3318.8008719476666;3326.0354497195785;3327.168207948013;1033.7622346860264;1040.5213601743208;1053.2258789789498;1053.7154968158554;1059.750958524746;12578.622641320684;12618.17981831178;12668.936655316757;12693.74612507754;12698.61904142868;4537;4587.449506059294;4632;4644.047970166108;4683.297505374153;10448.791024179474;10555.313904596176;10588.735281618021;10592.364458132537;10628;5236.319278493771;5240.161574148118;5253.054450199001;5260.421353651095;5305.681659100389;4280.101178752435;4757.500462451393;4886.389201349832;4895.118878601886;4902.534259245437;3895.5325360956867;3941.428492868575;4004.7797371144284;4008.8596899108393;4027.939580906289;1237.8390809194927;1260.974780504403;1274.0381012335627;1275.3495717184303;1275.432432567517;829.4525613095328;980.8773903262093;1042.9883013476915;1049.1344640671325;1062.442217835627;6054.757809687651;6062.545309101835;6069.848253793691;6079.878402431927;6222.160008398899;453.29738904697587;467.6422536759382;468.1199345230944;469.18127866872527;469.4507426311224;4066.4713587234064;4298.527162012176;4416.668749843775;4494.842680506166;4607.976960115178;11151.21941464106;11253.549858005752;11316.94341528287;11369.829452558219;11386.373749443861;11290.887091128983;11305;11313.377764222882;11330;11344.20590558653;7953.960230198812;8286.544240066854;8297.668093276323;8320.084790673021;8444.408891377667;6028.216331876828;6323.462505687068;6343.524235682435;6379.6810159490915;6434.967825160844;9703.951480242553;10145.289829712145;10327.380357178594;10353.9482302588;10479.895201048197;10269.178465722574;10326.845097323696;10374.118199953089;10419.791604167722;10506;7085.964570177116;7817.882731758915;8045.718399855977;8149.755507334789;8279.544625045439;8249.092599814136;8413.579321034047;8456.492610443393;8486.302739452258;8587.613557389808;7387.445941554298;7637.854321851767;7714.151443341116;7728;7791.454598178073;7135.964320178366;7248.456365772483;7260.564366138048;7334.926650733424;7389.334959853639;6581.111549940697;6675.399214070757;6718.7312507499155;6724.159480064992;6730.125083739278;4841.782119804688;5003.399592048978;5025.773840177128;5056.140456122542;5089.9745501272255;4993;5108.872278193075;5155.742212889416;5160;5225.895482090413 22:40:18 INFO - PROCESS | 12283 | |4;prototype.html;319.44416714916053;329.05396983671943;334.3263324401331;335.7498663495695;337.3186163948822;5460.481254280847;5496.367917689457;5574.498295153436;5602.579806514513;5702.429757024301;5523.944760552404;5549.306336707911;5556.721953950584;5582.162675598663;5606.943930560693;4390.604845563898;4390.692651514395;4395.120975804836;4398.538153493877;4407.360932664762;4188.958110418903;4395.736255824645;4412.249917514015;4414.315781053926;4445.288753799393;4349.673774466912;4349.69552131351;4361.171377438292;4367.716098453606;4403.9430536671125;4394.736315821045;4407.757573333465;4408.911821763579;4414.580614841585;4417.977910110461;3807.4669546263544;3875.9031024224337;3899.7270191086636;3900.0444891001885;3913.4129880517867;5365.409804921455;5366.8926621467745;5380.686304888896;5429.212764149176;5451.809186678487;4981.825636102719;5010.89978200434;5035.924461133086;5044.798208071673;5059.87350316245;5627.240322556484;5859.355470898197;5881.706134607506;5887.46925799291;5899.32157801854;4233.894152646178;4265.296226122717;4265.637420819234;4266.4240327556;4289.6997210195295;6078.088286756978;6083.48290395317;6097.878042439171;6105.664188469633;6134.693265336716;4301.548337424588;4361.825526978949;4372.672049596293;4372.693911426233;4377.905523619095;4390.3414487827085;4418.447694038246;4430.851037246933;4432.756198409118;4442.400735735133;5066.949330506648;5070.569001634829;5086.516780905808;5087.465816089331;5099.470158952374;5578.191162281447;5654.660720356709;5783.392743761844;5970.522358211333;5995.130706047686;1498.3182651893362;1521.4294639510183;1524.2759689147788;1528.4039224702376;1530.135473457493;970.538993977869;976.6728146071146;976.8241716490958;978.4178413843752;981.8625392445064;4942.431620363633;4954.232094025359;4962.60299176065;5025.899482010413;5034.967831594514;5251.293329667873;5359.812406565751;5444.121777986588;5453;5466.535344495763;5053.624796345785;5107.310513080761;5117.283580298761;5315.893682126413;5754.223179870663;3194.7923384979904;3195.3289809139874;3208.2300247940793;3213.5661685672603;3251.3822373749385;6109.114178444101;6219.626822390671;6314.936850631527;6316.115743795872;6384.680765961683;6126.632402055891;6161;6174.25908890936;6183.134361189347;6202.658853763086;6231.2522497300615;6280.937190628127;6283.7800676976085;6292.748290068437;6300.1494798202575;5838.5621078418435;5967.313758917607;6035.728392222449;6054.9091763624465;6067.271927368744;5942.257217847769;5958.318756559479;5976.910346344721;5977.372375900468;5982.790602328898;6038.849028774316;6271.811845644638;6275.341089185752;6276.340984196776;6313.40022697843;4239.97880010598;4261.339492378686;4270.786460677016;4279.465066866642;4288;5786.189933409242;5943.91084133747;6094.20775299226;6099.146119543356;6117.3576774439825;5902.6458412495385;5909.438603332676;5910.852228694145;5917.467427931507;5956.255468066492;5891.675957822189;5953.291252480025;5954.225950626391;5966.433188847052;5966.642001479925;5609.971950140386;5810.477057064884;5819.6508209507565;5819.883602327845;5842.737076831468;5959.851003724942;5963.165156877953;5976.193213916065;5978.7608495661425;5978.937266643001;5595.832125036255;5746.442832939302;5752.101806403793;5772.393898640582;5805.187273781589 22:40:18 INFO - PROCESS | 12283 | |5;yui.html;722.7145277615339;759.8480303939214;790.9327707144893;814.7474282972279;851.659336265494;14212.857871421296;14280.642983925407;14309.284535773222;14491.20298383591;14783.852161478395;13407.128536645112;13659.043866929318;13662.795058074113;13724.833389161908;13748.793768093487;797.0196658110527;803.4135081390604;822.1367564057746;824.1511243419258;828.6395417993162;817.7546735979212;818.1491249100928;821.1255013410698;823.4359463767339;823.8928939237891;864.844328020956;870.1211776106147;870.277669534285;870.3211495033884;872.7992561710779;854.5769843927234;860.5396113079535;860.8278344331159;861.3582880753847;862.2153840005627;628.8365025093462;629.131798118598;632.4624069540899;633.2590868683651;633.832034510855;13653.726925461535;13679.11085779431;13695.794563081561;13719.971002174876;13720.245386503739;13361.265130417823;13623.795643065361;13805.654858628615;13937.651558711012;13941.512047078304;10285.485725713685;10309.896901031043;10311.535980880879;10314.587416503331;10604.204684648605;3600.8199590020395;3632.600413954463;3659.1766852457986;3667;3740.756850804689;57672.42327576754;73763.63118184375;75763;75812;75866.62066689631;11008.174386921013;11075.833862492069;11205.831912521318;11229.831552526719;11555.248908821068;17139.828601714074;17176.31294748196;17179.7423038652;17197.7420338695;17291.221895014754;7968.521888686697;8176.581383613953;8270.586470676442;8326.167383261625;8377.246047855722;4406.27296496081;4443.2224360736745;4489.73061616304;4514.367988481616;4571.81712731487;3446.7414943879307;3530.1351168963256;3542.734294927891;3564.7326450515798;3579.480975258625;687.9931200688029;689.2728171778822;698.1482591238681;698.4935921456903;717.1322699533623;555.1035078348439;603.8973374526341;610.9511239100862;677.3869647968498;740.7222291640635;9331.486768227813;9750.463724495221;9884.258680598983;10303.330283531546;10350.741231469123;656.8898561431166;688.4320435640617;723.9710411583478;737.3179808676953;748.1770052941721;4306.884224401358;4524.5927866492175;4549.385832912581;4596.218642830727;4704.811807527661;67820;67969;68110.65944670246;99466.00533994712;111752;59826.40173598295;81908.77136842952;87132;96802.51598741961;97564.51217744008;85927.57036214779;86807.56596216979;86984;87127.56436217779;87311;8251.133630968661;8274.255317021498;8317.168283171635;8502.234798868132;8762.737117886474;50701.74649126879;51804.22293665523;52629.7368513155;52839;53739.73130134324;45583;51159.74420127876;75324;76113;76263;11539.711507212387;11599.420028998686;11726.824097638713;11879.524819007489;12183.147179697691;12025.939870300592;12124;12479.87520124787;12734.235945843278;12734.299613520974;11499.827502587636;11510.654680359603;11755;12023.819642705192;12132.878671213175;11258.493367798632;11602.187846850977;11693.181477296848;11774;11816.940915295712;11709.238899471507;11720.70698232551;12025.278483291031;12375.876241237833;12410.937945310216;8710.547090277896;8954.507502087448;8973;9280.860787088064;9572.377795443273;8742.38803283764;8947;9082.818343633222;9098.31762617793;9185.77035574116 22:40:18 INFO - PROCESS | 12283 | __end_tp_report 22:40:18 INFO - PROCESS | 12283 | __start_cc_report 22:40:18 INFO - PROCESS | 12283 | _x_x_mozilla_cycle_collect,335 22:40:18 INFO - PROCESS | 12283 | __end_cc_report 22:40:18 INFO - PROCESS | 12283 | __startTimestamp1465105218104__endTimestamp 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | ------- Summary: start ------- 22:40:18 INFO - PROCESS | 12283 | Number of tests: 6 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | [#0] dojo.html Cycles:165 Average:5325.34 Median:5540.83 stddev:692.35 (12.5%) stddev-sans-first:672.72 22:40:18 INFO - PROCESS | 12283 | Values: 3130.7 3135.7 3163.8 3169.7 3175.5 5422.1 5452.7 5510.0 5526.1 5542.2 5221.2 5346.8 5360.5 5413.9 5512.4 4805.5 4842.2 4932.4 4970.8 5013.6 875.9 4776.5 4851.7 4915.3 5013.1 4931.4 4961.8 4970.0 4980.1 4984.3 4985.6 4990.2 4991.7 4999.3 5015.4 4811.6 4823.9 4863.1 4868.8 4876.1 5251.3 5270.9 5276.0 5314.2 5319.8 5535.0 5712.3 5720.3 5725.0 5730.3 5762.4 5791.4 5817.2 5824.9 5877.2 5385.7 5398.5 5411.1 5420.7 5430.2 5731.2 5797.2 5805.6 5931.8 5963.9 5617.9 5665.3 5671.9 5728.5 5749.2 5819.5 5819.5 5821.1 5828.6 5836.5 5606.6 5760.7 5812.5 5817.5 5825.9 5711.7 5713.0 5752.7 5753.9 5759.1 4398.3 4399.6 4401.7 4408.3 4413.2 3857.1 3881.3 3932.4 3948.6 3985.6 5406.1 5459.3 5472.2 5475.3 5475.8 5692.3 5757.3 5787.9 5789.9 5806.6 5262.6 5379.6 5420.6 5436.0 5470.4 4901.2 4927.4 4942.1 4944.1 4952.9 5897.9 5900.4 5903.8 5916.2 5916.9 5605.6 5686.7 5713.4 5723.9 5773.3 5888.4 5911.4 5916.8 5925.6 5927.8 5571.9 5597.3 5633.5 5634.6 5661.3 5728.7 5776.0 5814.4 5833.1 5839.8 5711.5 5718.2 5724.0 5737.4 5738.2 5454.4 5598.6 5688.5 5691.5 5707.9 5425.9 5460.5 5526.8 5540.8 5657.2 5629.9 5639.6 5654.9 5667.5 5672.1 5382.1 5529.6 5534.0 5537.3 5558.8 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | [#1] ext.html Cycles:180 Average:57801.79 Median:4166.50 stddev:115140.66 (2763.5%) stddev-sans-first:115383.49 22:40:18 INFO - PROCESS | 12283 | Values: 575.6 607.4 621.4 626.5 635.4 43200.4 44756.6 44785.6 45018.1 48082.8 43371.8 44155.6 44305.6 44454.3 44486.0 8683.2 8827.2 8894.6 8901.0 8902.3 8672.1 8686.1 8709.7 8720.7 8725.8 8708.2 8742.7 8743.8 8750.1 8788.6 8722.4 8737.2 8746.2 8761.4 8762.5 8669.9 8693.7 8706.8 8712.8 8756.7 41314.0 41620.6 41636.0 41848.8 41883.0 3394.3 3419.0 3422.9 3456.5 3477.0 31037.2 31429.2 31519.4 33257.5 33262.3 4166.0 4167.0 4198.2 4306.8 4342.9 301872.0 302209.0 302473.0 303861.0 304779.0 55823.9 56934.0 56973.7 57027.0 57447.4 36215.8 37019.0 37036.0 59811.0 60399.0 17579.5 17732.1 17743.2 17761.2 17842.1 13192.3 13199.4 13207.3 13214.7 13227.0 19142.5 19226.8 19360.4 19398.0 19578.3 7271.0 7341.2 7367.9 7400.9 7444.0 999.7 1083.4 1107.2 1145.7 1227.6 32048.2 32793.5 32938.5 33003.3 33483.0 1099.7 1154.2 1186.3 1202.0 1242.0 17137.4 17192.7 17249.3 17295.8 17525.6 583416.0 585325.0 588466.0 588602.0 590843.0 39201.2 58769.0 59797.7 59925.0 59947.1 1356.7 1394.6 1411.7 1423.2 1425.8 31064.5 31988.0 32229.8 32258.4 32648.0 50749.5 51102.2 51200.0 51680.7 51695.2 352521.0 353999.0 355282.0 355399.0 355692.0 26628.9 26955.3 26982.1 27103.6 28620.0 46111.5 48760.0 49762.0 49877.3 50079.5 40562.6 42335.6 42343.0 42440.6 42840.6 41826.6 41873.4 42340.6 42347.4 42968.0 37158.8 41205.6 41637.0 42197.4 42548.1 35122.3 37779.8 37896.2 37959.6 38148.0 28424.5 28526.1 28578.7 28859.3 28915.6 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | [#2] jquery.html Cycles:180 Average:30169.94 Median:32072.34 stddev:81442.82 (253.9%) stddev-sans-first:81640.59 22:40:18 INFO - PROCESS | 12283 | Values: 389.9 391.6 400.8 401.4 418.8 13604.3 18007.0 18622.9 19478.0 19588.8 17784.4 18125.7 18579.3 18755.2 18801.3 5642.2 5673.3 5898.4 5906.9 5928.7 5319.9 5629.6 5813.8 5820.6 5861.0 5643.9 5647.5 5649.9 5655.6 5666.9 7056.3 7470.4 7475.7 7541.9 7572.6 6956.4 7200.4 7257.2 7262.7 7328.2 24074.5 24420.0 24839.4 25188.2 25240.2 2343.3 2414.3 2486.8 2553.2 2751.3 15345.4 15447.0 15522.7 15731.4 15979.4 2083.8 2154.1 2210.0 2216.8 2234.0 61980.7 92638.5 99549.0 100727.5 100871.0 16847.5 17578.9 17756.2 17938.6 18091.0 31630.0 31697.0 31970.0 32174.7 32219.0 9565.1 9643.3 10215.1 10288.1 10460.1 8663.5 8683.8 8750.3 8934.0 8950.0 8578.9 8783.6 8926.4 8967.0 8975.6 3458.2 3627.2 3657.2 3694.7 3711.9 985.5 1005.3 1007.9 1033.0 1052.4 17873.4 18144.5 18466.7 18489.7 18610.3 1033.8 1044.6 1056.3 1115.8 1141.6 9966.0 10337.3 10355.8 10387.9 10489.5 465501.0 473235.0 505089.0 512123.0 513819.0 54936.0 78799.6 87847.6 91905.0 98366.0 979.3 1016.2 1036.8 1039.1 1041.6 16696.2 17127.5 17198.6 17281.6 17771.9 40830.2 42474.0 42722.2 42960.6 43012.6 58667.0 59387.4 60559.0 60604.7 61461.7 8537.0 8545.1 8590.4 8723.8 8738.9 9875.8 12951.1 13144.1 13191.3 13278.0 5438.9 6436.9 11489.4 11606.9 11870.0 11004.2 11156.4 11174.6 11182.8 11225.0 11560.5 11625.5 11630.0 11749.8 11817.9 8589.0 8624.8 8696.0 8755.9 8895.8 8631.0 10626.7 11367.9 11542.9 11559.2 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | [#3] mootools.html Cycles:180 Average:5832.32 Median:4619.99 stddev:3397.69 (73.5%) stddev-sans-first:3391.43 22:40:18 INFO - PROCESS | 12283 | Values: 1472.6 1498.0 1665.4 1817.3 1831.3 7722.8 8576.9 8632.5 9169.0 9180.2 8741.1 8883.2 8904.5 8926.4 8988.4 2321.5 2323.2 2336.6 2339.2 2355.4 2226.8 2253.7 2270.0 2282.2 2288.9 2304.2 2310.5 2329.5 2347.3 2352.8 2228.4 2258.8 2260.1 2270.4 2274.9 2209.8 2216.5 2223.2 2237.5 2255.5 8919.2 8993.7 9016.9 9028.2 9048.4 5162.5 5242.0 5251.7 5285.1 5294.0 3035.5 3130.4 3318.8 3326.0 3327.2 1033.8 1040.5 1053.2 1053.7 1059.8 12578.6 12618.2 12668.9 12693.7 12698.6 4537.0 4587.4 4632.0 4644.0 4683.3 10448.8 10555.3 10588.7 10592.4 10628.0 5236.3 5240.2 5253.1 5260.4 5305.7 4280.1 4757.5 4886.4 4895.1 4902.5 3895.5 3941.4 4004.8 4008.9 4027.9 1237.8 1261.0 1274.0 1275.3 1275.4 829.5 980.9 1043.0 1049.1 1062.4 6054.8 6062.5 6069.8 6079.9 6222.2 453.3 467.6 468.1 469.2 469.5 4066.5 4298.5 4416.7 4494.8 4608.0 11151.2 11253.5 11316.9 11369.8 11386.4 11290.9 11305.0 11313.4 11330.0 11344.2 7954.0 8286.5 8297.7 8320.1 8444.4 6028.2 6323.5 6343.5 6379.7 6435.0 9704.0 10145.3 10327.4 10353.9 10479.9 10269.2 10326.8 10374.1 10419.8 10506.0 7086.0 7817.9 8045.7 8149.8 8279.5 8249.1 8413.6 8456.5 8486.3 8587.6 7387.4 7637.9 7714.2 7728.0 7791.5 7136.0 7248.5 7260.6 7334.9 7389.3 6581.1 6675.4 6718.7 6724.2 6730.1 4841.8 5003.4 5025.8 5056.1 5090.0 4993.0 5108.9 5155.7 5160.0 5225.9 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | [#4] prototype.html Cycles:180 Average:4918.41 Median:5510.16 stddev:1440.69 (26.1%) stddev-sans-first:1402.77 22:40:18 INFO - PROCESS | 12283 | Values: 319.4 329.1 334.3 335.7 337.3 5460.5 5496.4 5574.5 5602.6 5702.4 5523.9 5549.3 5556.7 5582.2 5606.9 4390.6 4390.7 4395.1 4398.5 4407.4 4189.0 4395.7 4412.2 4414.3 4445.3 4349.7 4349.7 4361.2 4367.7 4403.9 4394.7 4407.8 4408.9 4414.6 4418.0 3807.5 3875.9 3899.7 3900.0 3913.4 5365.4 5366.9 5380.7 5429.2 5451.8 4981.8 5010.9 5035.9 5044.8 5059.9 5627.2 5859.4 5881.7 5887.5 5899.3 4233.9 4265.3 4265.6 4266.4 4289.7 6078.1 6083.5 6097.9 6105.7 6134.7 4301.5 4361.8 4372.7 4372.7 4377.9 4390.3 4418.4 4430.9 4432.8 4442.4 5066.9 5070.6 5086.5 5087.5 5099.5 5578.2 5654.7 5783.4 5970.5 5995.1 1498.3 1521.4 1524.3 1528.4 1530.1 970.5 976.7 976.8 978.4 981.9 4942.4 4954.2 4962.6 5025.9 5035.0 5251.3 5359.8 5444.1 5453.0 5466.5 5053.6 5107.3 5117.3 5315.9 5754.2 3194.8 3195.3 3208.2 3213.6 3251.4 6109.1 6219.6 6314.9 6316.1 6384.7 6126.6 6161.0 6174.3 6183.1 6202.7 6231.3 6280.9 6283.8 6292.7 6300.1 5838.6 5967.3 6035.7 6054.9 6067.3 5942.3 5958.3 5976.9 5977.4 5982.8 6038.8 6271.8 6275.3 6276.3 6313.4 4240.0 4261.3 4270.8 4279.5 4288.0 5786.2 5943.9 6094.2 6099.1 6117.4 5902.6 5909.4 5910.9 5917.5 5956.3 5891.7 5953.3 5954.2 5966.4 5966.6 5610.0 5810.5 5819.7 5819.9 5842.7 5959.9 5963.2 5976.2 5978.8 5978.9 5595.8 5746.4 5752.1 5772.4 5805.2 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - PROCESS | 12283 | [#5] yui.html Cycles:180 Average:18476.72 Median:29113.76 stddev:26253.80 (90.2%) stddev-sans-first:26293.60 22:40:18 INFO - PROCESS | 12283 | Values: 722.7 759.8 790.9 814.7 851.7 14212.9 14280.6 14309.3 14491.2 14783.9 13407.1 13659.0 13662.8 13724.8 13748.8 797.0 803.4 822.1 824.2 828.6 817.8 818.1 821.1 823.4 823.9 864.8 870.1 870.3 870.3 872.8 854.6 860.5 860.8 861.4 862.2 628.8 629.1 632.5 633.3 633.8 13653.7 13679.1 13695.8 13720.0 13720.2 13361.3 13623.8 13805.7 13937.7 13941.5 10285.5 10309.9 10311.5 10314.6 10604.2 3600.8 3632.6 3659.2 3667.0 3740.8 57672.4 73763.6 75763.0 75812.0 75866.6 11008.2 11075.8 11205.8 11229.8 11555.2 17139.8 17176.3 17179.7 17197.7 17291.2 7968.5 8176.6 8270.6 8326.2 8377.2 4406.3 4443.2 4489.7 4514.4 4571.8 3446.7 3530.1 3542.7 3564.7 3579.5 688.0 689.3 698.1 698.5 717.1 555.1 603.9 611.0 677.4 740.7 9331.5 9750.5 9884.3 10303.3 10350.7 656.9 688.4 724.0 737.3 748.2 4306.9 4524.6 4549.4 4596.2 4704.8 67820.0 67969.0 68110.7 99466.0 111752.0 59826.4 81908.8 87132.0 96802.5 97564.5 85927.6 86807.6 86984.0 87127.6 87311.0 8251.1 8274.3 8317.2 8502.2 8762.7 50701.7 51804.2 52629.7 52839.0 53739.7 45583.0 51159.7 75324.0 76113.0 76263.0 11539.7 11599.4 11726.8 11879.5 12183.1 12025.9 12124.0 12479.9 12734.2 12734.3 11499.8 11510.7 11755.0 12023.8 12132.9 11258.5 11602.2 11693.2 11774.0 11816.9 11709.2 11720.7 12025.3 12375.9 12410.9 8710.5 8954.5 8973.0 9280.9 9572.4 8742.4 8947.0 9082.8 9098.3 9185.8 22:40:18 INFO - PROCESS | 12283 | -------- Summary: end -------- 22:40:18 INFO - PROCESS | 12283 | 22:40:18 INFO - TEST-INFO | 12283: exit 0 22:40:18 INFO - TEST-OK | dromaeo_css | took 1110862ms 22:40:18 INFO - TEST-START | kraken 22:40:18 INFO - operating with platform_type : linux_ 22:40:18 INFO - Initialising browser for kraken test... 22:40:18 INFO - TEST-INFO | started process 12513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp74bnM_/profile http://localhost:44393/getInfo.html) 22:40:23 INFO - PROCESS | 12513 | __metrics Screen width/height:1600/1200 22:40:23 INFO - PROCESS | 12513 | colorDepth:24 22:40:23 INFO - PROCESS | 12513 | Browser inner width/height: 1024/697 22:40:23 INFO - PROCESS | 12513 | __metrics 22:40:24 ERROR - PROCESS | 12513 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 22:40:24 INFO - TEST-INFO | 12513: exit 0 22:40:24 INFO - Browser initialized. 22:40:24 INFO - Running cycle 1/1 for kraken test... 22:40:24 INFO - TEST-INFO | started process 12631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp74bnM_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:36 INFO - PROCESS | 12631 | RSS: Main: 167526400 22:40:36 INFO - PROCESS | 12631 | 22:40:39 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44393/tests/kraken/driver.html?testName=audio-beat-detection) 22:40:40 INFO - PROCESS | 12631 | RSS: Main: 172949504 22:40:40 INFO - PROCESS | 12631 | 22:40:42 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44393/tests/kraken/driver.html?testName=audio-dft) 22:40:43 INFO - PROCESS | 12631 | RSS: Main: 172089344 22:40:43 INFO - PROCESS | 12631 | 22:40:46 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44393/tests/kraken/driver.html?testName=audio-fft) 22:40:47 INFO - PROCESS | 12631 | RSS: Main: 166567936 22:40:47 INFO - PROCESS | 12631 | 22:40:49 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44393/tests/kraken/driver.html?testName=audio-oscillator) 22:40:49 INFO - PROCESS | 12631 | RSS: Main: 167854080 22:40:49 INFO - PROCESS | 12631 | 22:40:51 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44393/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:40:52 INFO - PROCESS | 12631 | RSS: Main: 168042496 22:40:52 INFO - PROCESS | 12631 | 22:40:55 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44393/tests/kraken/driver.html?testName=imaging-darkroom) 22:40:56 INFO - PROCESS | 12631 | RSS: Main: 167501824 22:40:56 INFO - PROCESS | 12631 | 22:40:59 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44393/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:00 INFO - PROCESS | 12631 | RSS: Main: 168353792 22:41:00 INFO - PROCESS | 12631 | 22:41:03 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44393/tests/kraken/driver.html?testName=json-parse-financial) 22:41:03 INFO - PROCESS | 12631 | RSS: Main: 168026112 22:41:03 INFO - PROCESS | 12631 | 22:41:05 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44393/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:05 INFO - PROCESS | 12631 | RSS: Main: 168026112 22:41:05 INFO - PROCESS | 12631 | 22:41:07 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:08 INFO - PROCESS | 12631 | RSS: Main: 168529920 22:41:08 INFO - PROCESS | 12631 | 22:41:10 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:41:11 INFO - PROCESS | 12631 | RSS: Main: 169099264 22:41:11 INFO - PROCESS | 12631 | 22:41:13 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:41:14 INFO - PROCESS | 12631 | RSS: Main: 168841216 22:41:14 INFO - PROCESS | 12631 | 22:41:17 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:41:17 INFO - PROCESS | 12631 | RSS: Main: 168730624 22:41:17 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | Cycle 1(1): loaded http://localhost:44393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44393/tests/kraken/driver.html?testName=ai-astar) 22:41:19 INFO - PROCESS | 12631 | RSS: Main: 169623552 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | __start_tp_report 22:41:19 INFO - PROCESS | 12631 | _x_x_mozilla_page_load 22:41:19 INFO - PROCESS | 12631 | _x_x_mozilla_page_load_details 22:41:19 INFO - PROCESS | 12631 | |i|pagename|runs| 22:41:19 INFO - PROCESS | 12631 | |0;ai-astar;111;107;111;110;111;111;112;112;110;112 22:41:19 INFO - PROCESS | 12631 | |1;audio-beat-detection;157;125;158;177;128;154;172;134;180;126 22:41:19 INFO - PROCESS | 12631 | |2;audio-dft;172;163;161;165;162;166;178;162;161;161 22:41:19 INFO - PROCESS | 12631 | |3;audio-fft;75;75;77;169;74;76;74;77;157;78 22:41:19 INFO - PROCESS | 12631 | |4;audio-oscillator;86;85;147;86;85;90;144;85;85;151 22:41:19 INFO - PROCESS | 12631 | |5;imaging-gaussian-blur;118;118;117;117;118;117;117;119;118;117 22:41:19 INFO - PROCESS | 12631 | |6;imaging-darkroom;149;148;147;146;149;147;148;147;146;147 22:41:19 INFO - PROCESS | 12631 | |7;imaging-desaturate;95;96;95;95;96;98;95;96;95;95 22:41:19 INFO - PROCESS | 12631 | |8;json-parse-financial;54;56;59;57;54;60;55;55;54;55 22:41:19 INFO - PROCESS | 12631 | |9;json-stringify-tinderbox;62;61;61;62;65;62;63;62;63;61 22:41:19 INFO - PROCESS | 12631 | |10;stanford-crypto-aes;80;82;86;83;80;83;86;80;106;80 22:41:19 INFO - PROCESS | 12631 | |11;stanford-crypto-ccm;134;123;136;129;130;127;131;125;141;139 22:41:19 INFO - PROCESS | 12631 | |12;stanford-crypto-pbkdf2;163;164;162;164;160;162;160;162;163;161 22:41:19 INFO - PROCESS | 12631 | |13;stanford-crypto-sha256-iterative;64;64;67;68;63;63;65;63;61;61 22:41:19 INFO - PROCESS | 12631 | __end_tp_report 22:41:19 INFO - PROCESS | 12631 | __start_cc_report 22:41:19 INFO - PROCESS | 12631 | _x_x_mozilla_cycle_collect,882 22:41:19 INFO - PROCESS | 12631 | __end_cc_report 22:41:19 INFO - PROCESS | 12631 | __startTimestamp1465105279645__endTimestamp 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | ------- Summary: start ------- 22:41:19 INFO - PROCESS | 12631 | Number of tests: 14 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:1.49 (1.3%) stddev-sans-first:1.58 22:41:19 INFO - PROCESS | 12631 | Values: 111.0 107.0 111.0 110.0 111.0 111.0 112.0 112.0 110.0 112.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#1] audio-beat-detection Cycles:10 Average:151.10 Median:157.50 stddev:21.53 (13.7%) stddev-sans-first:22.73 22:41:19 INFO - PROCESS | 12631 | Values: 157.0 125.0 158.0 177.0 128.0 154.0 172.0 134.0 180.0 126.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#2] audio-dft Cycles:10 Average:165.10 Median:164.00 stddev:5.67 (3.5%) stddev-sans-first:5.43 22:41:19 INFO - PROCESS | 12631 | Values: 172.0 163.0 161.0 165.0 162.0 166.0 178.0 162.0 161.0 161.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#3] audio-fft Cycles:10 Average:93.20 Median:75.50 stddev:36.92 (48.9%) stddev-sans-first:38.57 22:41:19 INFO - PROCESS | 12631 | Values: 75.0 75.0 77.0 169.0 74.0 76.0 74.0 77.0 157.0 78.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#4] audio-oscillator Cycles:10 Average:104.40 Median:85.00 stddev:29.71 (35.0%) stddev-sans-first:30.76 22:41:19 INFO - PROCESS | 12631 | Values: 86.0 85.0 147.0 86.0 85.0 90.0 144.0 85.0 85.0 151.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 22:41:19 INFO - PROCESS | 12631 | Values: 118.0 118.0 117.0 117.0 118.0 117.0 117.0 119.0 118.0 117.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:1.07 (0.7%) stddev-sans-first:0.97 22:41:19 INFO - PROCESS | 12631 | Values: 149.0 148.0 147.0 146.0 149.0 147.0 148.0 147.0 146.0 147.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 22:41:19 INFO - PROCESS | 12631 | Values: 95.0 96.0 95.0 95.0 96.0 98.0 95.0 96.0 95.0 95.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:55.50 stddev:2.13 (3.8%) stddev-sans-first:2.15 22:41:19 INFO - PROCESS | 12631 | Values: 54.0 56.0 59.0 57.0 54.0 60.0 55.0 55.0 54.0 55.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 22:41:19 INFO - PROCESS | 12631 | Values: 62.0 61.0 61.0 62.0 65.0 62.0 63.0 62.0 63.0 61.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:82.50 stddev:7.88 (9.5%) stddev-sans-first:8.18 22:41:19 INFO - PROCESS | 12631 | Values: 80.0 82.0 86.0 83.0 80.0 83.0 86.0 80.0 106.0 80.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:132.50 stddev:5.93 (4.5%) stddev-sans-first:6.22 22:41:19 INFO - PROCESS | 12631 | Values: 134.0 123.0 136.0 129.0 130.0 127.0 131.0 125.0 141.0 139.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.50 stddev:1.45 (0.9%) stddev-sans-first:1.50 22:41:19 INFO - PROCESS | 12631 | Values: 163.0 164.0 162.0 164.0 160.0 162.0 160.0 162.0 163.0 161.0 22:41:19 INFO - PROCESS | 12631 | 22:41:19 INFO - PROCESS | 12631 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:2.28 (3.6%) stddev-sans-first:2.42 22:41:19 INFO - PROCESS | 12631 | Values: 64.0 64.0 67.0 68.0 63.0 63.0 65.0 63.0 61.0 61.0 22:41:19 INFO - PROCESS | 12631 | -------- Summary: end -------- 22:41:19 INFO - PROCESS | 12631 | 22:41:20 INFO - TEST-INFO | 12631: exit 0 22:41:20 INFO - TEST-OK | kraken | took 61735ms 22:41:20 INFO - SUITE-END | took 1172s 22:41:20 INFO - Completed test suite (00:19:32) 22:41:20 INFO - javascript benchmark 22:41:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7317.710267568166, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3130.6869313068696, 3135.686431356864, 3163.794353367031, 3169.6988786065344, 3175.491921292594, 5422.078246698061, 5452.727363631822, 5509.972450137754, 5526.088195447746, 5542.196381524676, 5221.24291977664, 5346.812861549846, 5360.517553420192, 5413.864653383657, 5512.366077901042, 4805.543473370033, 4842.249451335048, 4932.432770231416, 4970.776315065813, 5013.573846223076, 875.8579405176988, 4776.450708168571, 4851.733154676492, 4915.2872833439305, 5013.097642424362, 4931.3835770528685, 4961.801527938878, 4970.030843985424, 4980.078685443204, 4984.252362145671, 4985.626078044161, 4990.201567749179, 4991.650584459089, 4999.250112483121, 5015.39815222172, 4811.615070794328, 4823.903521929542, 4863.100326439585, 4868.829590964299, 4876.122297986397, 5251.317328747276, 5270.868228294324, 5276.023935571931, 5314.229436731653, 5319.787208511655, 5534.972325138348, 5712.314522257314, 5720.2849643794525, 5724.971375143139, 5730.283714535683, 5762.394948530427, 5791.391903850077, 5817.243758311435, 5824.854378640525, 5877.177195192677, 5385.703786291752, 5398.460153984609, 5411.053065713484, 5420.728963551806, 5430.185472179205, 5731.226284451545, 5797.246357973437, 5805.622634528742, 5931.7923872665115, 5963.940360596425, 5617.915731264035, 5665.348484924287, 5671.943280567224, 5728.484436400744, 5749.223854779635, 5819.476247137778, 5819.5053420459735, 5821.126830975303, 5828.59199856006, 5836.533077353802, 5606.607537472338, 5760.74076666551, 5812.476877081083, 5817.476427121579, 5825.94174058254, 5711.742971566289, 5712.971435142797, 5752.712364381764, 5753.942460575425, 5759.107338362561, 4398.3402489626815, 4399.6040356368085, 4401.713888597231, 4408.294672852392, 4413.205622987893, 3857.0935830079907, 3881.2819628368843, 3932.4297976793778, 3948.565657777642, 3985.6412922837085, 5406.08096623575, 5459.344878614592, 5472.151816468454, 5475.288212532345, 5475.835724928179, 5692.316921969307, 5757.280339957505, 5787.884242315215, 5789.913151302734, 5806.622569532967, 5262.631615786935, 5379.569634429236, 5420.647657902224, 5436.0, 5470.370907345627, 4901.240307752304, 4927.384076990376, 4942.061008408391, 4944.1100601891285, 4952.925706114411, 5897.911531326948, 5900.4099590041515, 5903.793367232126, 5916.23088998436, 5916.911246331309, 5605.607607467438, 5686.744096515584, 5713.428657134254, 5723.914141287967, 5773.278340207474, 5888.411158884077, 5911.379305172895, 5916.792912248051, 5925.585209035326, 5927.8221653350465, 5571.916421253603, 5597.3003374578175, 5633.549316054788, 5634.6337488063145, 5661.348944871393, 5728.684922329312, 5776.046952252831, 5814.389489103583, 5833.066709326487, 5839.795607153729, 5711.485966263056, 5718.228039214653, 5723.971380143073, 5737.426257374396, 5738.167965644875, 5454.372747134131, 5598.580106491948, 5688.488036076773, 5691.4877661010705, 5707.857303567444, 5425.918611220756, 5460.453954604508, 5526.834194974157, 5540.833774986757, 5657.179708942098, 5629.874025194896, 5639.577031722719, 5654.943450565442, 5667.4615911488345, 5672.064109421899, 5382.138857782736, 5529.585281104014, 5534.0, 5537.335519737658, 5558.805441809679], "value": 5281.057989868409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.5798267264896, 607.4017093163234, 621.4407033669694, 626.4518546272009, 635.4439865118022, 43200.35199472006, 44756.552434475605, 44785.55214447855, 45018.09963800722, 48082.75958620202, 43371.78314108433, 44155.55844441555, 44305.556944430544, 44454.33318500225, 44486.0, 8683.218510334053, 8827.249683776887, 8894.644214231423, 8900.955495222548, 8902.332325075613, 8672.089430609789, 8686.087960764124, 8709.738707838775, 8720.651173953034, 8725.781855453633, 8708.21626053655, 8742.737717868442, 8743.825123497527, 8750.124987501262, 8788.560571971408, 8722.38943273968, 8737.213650771397, 8746.169113934166, 8761.386702930797, 8762.518061506615, 8669.869951950726, 8693.652253909837, 8706.825863482694, 8712.782180445476, 8756.737297881074, 41314.0, 41620.583794161976, 41636.0, 41848.79075604633, 41883.0, 3394.2871996880685, 3419.0, 3422.9144271393166, 3456.5333679953137, 3476.9826150869085, 31037.22406939822, 31429.214269643213, 31519.42402879847, 33257.501137482956, 33262.33475330504, 4166.0, 4167.0, 4198.223328684173, 4306.849260275875, 4342.891427714301, 301872.0, 302209.0, 302473.0, 303861.0, 304779.0, 55823.88352232933, 56934.0, 56973.715131424906, 57027.0, 57447.42552574504, 36215.81892090523, 37019.0, 37036.0, 59811.0, 60399.0, 17579.472615821545, 17732.11339433023, 17743.201555930013, 17761.200745966464, 17842.10789460522, 13192.340382980812, 13199.40602672882, 13207.273599952096, 13214.73570528584, 13227.0, 19142.521436963907, 19226.80773192278, 19360.4191874244, 19398.0, 19578.314758983368, 7270.963645181846, 7341.155767086747, 7367.852642947111, 7400.888986665204, 7444.0, 999.7050869993369, 1083.4474418046852, 1107.197281970581, 1145.7422080032093, 1227.6071657069651, 32048.198795030312, 32793.50809737856, 32938.50592241118, 33003.34983250828, 33483.0, 1099.6536091131427, 1154.1805318223987, 1186.2882270637722, 1201.9519219231308, 1242.031215651793, 17137.400190993256, 17192.65614687724, 17249.310027599007, 17295.827041729422, 17525.64948701019, 583416.0, 585325.0, 588466.0, 588602.0, 590843.0, 39201.21597568033, 58769.0, 59797.701011495534, 59925.0, 59947.100793488135, 1356.7083077138464, 1394.6304229379223, 1411.7317709635136, 1423.1745587559192, 1425.7932599772976, 31064.534031989086, 31988.0, 32229.838850805594, 32258.35483290368, 32648.0, 50749.492505075956, 51102.23346649729, 51200.0, 51680.74159629178, 51695.22457163221, 352521.0, 353999.0, 355282.0, 355399.0, 355692.0, 26628.866855665598, 26955.326116847235, 26982.055628052924, 27103.59344609793, 28620.0, 46111.53888461207, 48760.0, 49762.0, 49877.25184122168, 50079.49920500749, 40562.594374057066, 42335.576644233166, 42343.0, 42440.57559424366, 42840.57159428366, 41826.58173418227, 41873.37189942093, 42340.576594234895, 42347.3647895288, 42968.0, 37158.8142059288, 41205.587944120176, 41637.0, 42197.367039495046, 42548.149037019706, 35122.297554049284, 37779.81110094432, 37896.24207515779, 37959.62040379671, 38148.0, 28424.49183189924, 28526.144215673565, 28578.71421285844, 28859.278518037216, 28915.566266505597], "value": 19985.229645304615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.87913746738514, 391.6318660459168, 400.8436709683224, 401.3618346828542, 418.77176938568476, 13604.251766152858, 18007.0, 18622.90688546559, 19478.026098695063, 19588.804111958878, 17784.377546785865, 18125.728114078265, 18579.25682972683, 18755.15601797919, 18801.341953031646, 5642.181883626871, 5673.319201695792, 5898.439648233411, 5906.911396329058, 5928.703564821763, 5319.9202011969655, 5629.60592758507, 5813.796517121901, 5820.563457740686, 5860.97069514654, 5643.915341269884, 5647.519960803316, 5649.9152512712135, 5655.604107712462, 5666.914996275059, 7056.329648683367, 7470.3650189733935, 7475.700971961142, 7541.924580754232, 7572.621368931532, 6956.408705260059, 7200.351968322876, 7257.165425976027, 7262.673179706926, 7328.157261914895, 24074.518509629885, 24420.0, 24839.379015524577, 25188.244352669448, 25240.242792716246, 2343.2970108967243, 2414.2998530426157, 2486.76375744304, 2553.19574334086, 2751.339678477171, 15345.386184552604, 15447.0, 15522.689546209012, 15731.44939927097, 15979.360825566964, 2083.812456878873, 2154.138344662132, 2210.0, 2216.8115710164657, 2233.9888300558555, 61980.69009654968, 92638.53680731621, 99549.0, 100727.49636251845, 100871.0, 16847.494575162764, 17578.86896579303, 17756.20097095634, 17938.641227175645, 18091.0, 31630.0, 31697.0, 31970.0, 32174.678253217167, 32219.0, 9565.091316324939, 9643.27675424346, 10215.131713804261, 10288.074073333435, 10460.110890574235, 8663.523506207219, 8683.782905427288, 8750.343724220582, 8934.0, 8950.0, 8578.898169948403, 8783.58451425863, 8926.38481415028, 8966.955165224132, 8975.596098175598, 3458.239187378773, 3627.220147668264, 3657.23198128396, 3694.741368104261, 3711.870084547028, 985.4776968206862, 1005.3163848583034, 1007.9193664506822, 1032.9793404131876, 1052.4001319247955, 17873.374431894823, 18144.546386340447, 18466.722999155023, 18489.7226541602, 18610.348637797884, 1033.8087453821067, 1044.5873879817582, 1056.2553399853239, 1115.8382034604938, 1141.634676903383, 9966.0, 10337.328073675188, 10355.792884142274, 10387.896121038844, 10489.47552622381, 465501.0, 473235.0, 505089.0, 512123.0, 513819.0, 54936.0, 78799.60600196962, 87847.56076219706, 91905.0, 98365.99999999857, 979.2557656181211, 1016.2022812092472, 1036.7926414716935, 1039.1063685230695, 1041.5677493840124, 16696.248668810174, 17127.486175414757, 17198.570035749206, 17281.56796080108, 17771.911140444216, 40830.183396332504, 42474.0, 42722.213356737615, 42960.57039429565, 43012.569874300854, 58667.0, 59387.40612593992, 60559.0, 60604.69697651484, 61461.692691538046, 8536.951131728465, 8545.102764209669, 8590.441621294596, 8723.78190545216, 8738.868916966123, 9875.802483950138, 12951.093423460266, 13144.0799144059, 13191.340432978504, 13278.0, 5438.945610544002, 6436.871262574816, 11489.425528723363, 11606.88393116058, 11870.0, 11004.229703920648, 11156.386398748147, 11174.608888688857, 11182.776344473228, 11225.0, 11560.537578496765, 11625.534978600761, 11630.0, 11749.823752643888, 11817.881821182022, 8588.969323680949, 8624.827503450022, 8696.043435221995, 8755.91244087551, 8895.777605559913, 8631.0, 10626.681199563716, 11367.943160284476, 11542.94228528852, 11559.19085664029], "value": 10178.649987367056, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1472.558232530241, 1498.0487390507028, 1665.4087798831415, 1817.3194538056368, 1831.331563979148, 7722.806929826743, 8576.91423085769, 8632.525211113387, 9168.954155229223, 9180.173784359415, 8741.125887411255, 8883.200511953923, 8904.51025193614, 8926.41978271412, 8988.370814043019, 2321.4892723600783, 2323.175272778165, 2336.6378211377264, 2339.2163625185585, 2355.4346956730346, 2226.7661895500983, 2253.6957510736006, 2270.0465804362125, 2282.2012295696454, 2288.942776430586, 2304.2050492580033, 2310.4635417447375, 2329.4642232286583, 2347.3427440316655, 2352.7882490575844, 2228.387193521778, 2258.7854153855355, 2260.0959616153505, 2270.4437412833795, 2274.8748818814875, 2209.845310828243, 2216.4569680428235, 2223.1663126327626, 2237.5301186750803, 2255.537614788964, 8919.24186444153, 8993.730188094367, 9016.8647470288, 9028.232600228988, 9048.366614337, 5162.45794191612, 5242.0, 5251.658642188246, 5285.07511185541, 5293.9735301323635, 3035.48396772547, 3130.3739252149435, 3318.8008719476666, 3326.0354497195785, 3327.168207948013, 1033.7622346860264, 1040.5213601743208, 1053.2258789789498, 1053.7154968158554, 1059.750958524746, 12578.622641320684, 12618.17981831178, 12668.936655316757, 12693.74612507754, 12698.61904142868, 4537.0, 4587.449506059294, 4632.0, 4644.047970166108, 4683.297505374153, 10448.791024179474, 10555.313904596176, 10588.735281618021, 10592.364458132537, 10628.0, 5236.319278493771, 5240.161574148118, 5253.054450199001, 5260.421353651095, 5305.681659100389, 4280.101178752435, 4757.500462451393, 4886.389201349832, 4895.118878601886, 4902.534259245437, 3895.5325360956867, 3941.428492868575, 4004.7797371144284, 4008.8596899108393, 4027.939580906289, 1237.8390809194927, 1260.974780504403, 1274.0381012335627, 1275.3495717184303, 1275.432432567517, 829.4525613095328, 980.8773903262093, 1042.9883013476915, 1049.1344640671325, 1062.442217835627, 6054.757809687651, 6062.545309101835, 6069.848253793691, 6079.878402431927, 6222.160008398899, 453.29738904697587, 467.6422536759382, 468.1199345230944, 469.18127866872527, 469.4507426311224, 4066.4713587234064, 4298.527162012176, 4416.668749843775, 4494.842680506166, 4607.976960115178, 11151.21941464106, 11253.549858005752, 11316.94341528287, 11369.829452558219, 11386.373749443861, 11290.887091128983, 11305.0, 11313.377764222882, 11330.0, 11344.20590558653, 7953.960230198812, 8286.544240066854, 8297.668093276323, 8320.084790673021, 8444.408891377667, 6028.216331876828, 6323.462505687068, 6343.524235682435, 6379.6810159490915, 6434.967825160844, 9703.951480242553, 10145.289829712145, 10327.380357178594, 10353.9482302588, 10479.895201048197, 10269.178465722574, 10326.845097323696, 10374.118199953089, 10419.791604167722, 10506.0, 7085.964570177116, 7817.882731758915, 8045.718399855977, 8149.755507334789, 8279.544625045439, 8249.092599814136, 8413.579321034047, 8456.492610443393, 8486.302739452258, 8587.613557389808, 7387.445941554298, 7637.854321851767, 7714.151443341116, 7728.0, 7791.454598178073, 7135.964320178366, 7248.456365772483, 7260.564366138048, 7334.926650733424, 7389.334959853639, 6581.111549940697, 6675.399214070757, 6718.7312507499155, 6724.159480064992, 6730.125083739278, 4841.782119804688, 5003.399592048978, 5025.773840177128, 5056.140456122542, 5089.9745501272255, 4993.0, 5108.872278193075, 5155.742212889416, 5160.0, 5225.895482090413], "value": 4563.106448904468, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.44416714916053, 329.05396983671943, 334.3263324401331, 335.7498663495695, 337.3186163948822, 5460.481254280847, 5496.367917689457, 5574.498295153436, 5602.579806514513, 5702.429757024301, 5523.944760552404, 5549.306336707911, 5556.721953950584, 5582.162675598663, 5606.943930560693, 4390.604845563898, 4390.692651514395, 4395.120975804836, 4398.538153493877, 4407.360932664762, 4188.958110418903, 4395.736255824645, 4412.249917514015, 4414.315781053926, 4445.288753799393, 4349.673774466912, 4349.69552131351, 4361.171377438292, 4367.716098453606, 4403.9430536671125, 4394.736315821045, 4407.757573333465, 4408.911821763579, 4414.580614841585, 4417.977910110461, 3807.4669546263544, 3875.9031024224337, 3899.7270191086636, 3900.0444891001885, 3913.4129880517867, 5365.409804921455, 5366.8926621467745, 5380.686304888896, 5429.212764149176, 5451.809186678487, 4981.825636102719, 5010.89978200434, 5035.924461133086, 5044.798208071673, 5059.87350316245, 5627.240322556484, 5859.355470898197, 5881.706134607506, 5887.46925799291, 5899.32157801854, 4233.894152646178, 4265.296226122717, 4265.637420819234, 4266.4240327556, 4289.6997210195295, 6078.088286756978, 6083.48290395317, 6097.878042439171, 6105.664188469633, 6134.693265336716, 4301.548337424588, 4361.825526978949, 4372.672049596293, 4372.693911426233, 4377.905523619095, 4390.3414487827085, 4418.447694038246, 4430.851037246933, 4432.756198409118, 4442.400735735133, 5066.949330506648, 5070.569001634829, 5086.516780905808, 5087.465816089331, 5099.470158952374, 5578.191162281447, 5654.660720356709, 5783.392743761844, 5970.522358211333, 5995.130706047686, 1498.3182651893362, 1521.4294639510183, 1524.2759689147788, 1528.4039224702376, 1530.135473457493, 970.538993977869, 976.6728146071146, 976.8241716490958, 978.4178413843752, 981.8625392445064, 4942.431620363633, 4954.232094025359, 4962.60299176065, 5025.899482010413, 5034.967831594514, 5251.293329667873, 5359.812406565751, 5444.121777986588, 5453.0, 5466.535344495763, 5053.624796345785, 5107.310513080761, 5117.283580298761, 5315.893682126413, 5754.223179870663, 3194.7923384979904, 3195.3289809139874, 3208.2300247940793, 3213.5661685672603, 3251.3822373749385, 6109.114178444101, 6219.626822390671, 6314.936850631527, 6316.115743795872, 6384.680765961683, 6126.632402055891, 6161.0, 6174.25908890936, 6183.134361189347, 6202.658853763086, 6231.2522497300615, 6280.937190628127, 6283.7800676976085, 6292.748290068437, 6300.1494798202575, 5838.5621078418435, 5967.313758917607, 6035.728392222449, 6054.9091763624465, 6067.271927368744, 5942.257217847769, 5958.318756559479, 5976.910346344721, 5977.372375900468, 5982.790602328898, 6038.849028774316, 6271.811845644638, 6275.341089185752, 6276.340984196776, 6313.40022697843, 4239.97880010598, 4261.339492378686, 4270.786460677016, 4279.465066866642, 4288.0, 5786.189933409242, 5943.91084133747, 6094.20775299226, 6099.146119543356, 6117.3576774439825, 5902.6458412495385, 5909.438603332676, 5910.852228694145, 5917.467427931507, 5956.255468066492, 5891.675957822189, 5953.291252480025, 5954.225950626391, 5966.433188847052, 5966.642001479925, 5609.971950140386, 5810.477057064884, 5819.6508209507565, 5819.883602327845, 5842.737076831468, 5959.851003724942, 5963.165156877953, 5976.193213916065, 5978.7608495661425, 5978.937266643001, 5595.832125036255, 5746.442832939302, 5752.101806403793, 5772.393898640582, 5805.187273781589], "value": 4453.57494091985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.7145277615339, 759.8480303939214, 790.9327707144893, 814.7474282972279, 851.659336265494, 14212.857871421296, 14280.642983925407, 14309.284535773222, 14491.20298383591, 14783.852161478395, 13407.128536645112, 13659.043866929318, 13662.795058074113, 13724.833389161908, 13748.793768093487, 797.0196658110527, 803.4135081390604, 822.1367564057746, 824.1511243419258, 828.6395417993162, 817.7546735979212, 818.1491249100928, 821.1255013410698, 823.4359463767339, 823.8928939237891, 864.844328020956, 870.1211776106147, 870.277669534285, 870.3211495033884, 872.7992561710779, 854.5769843927234, 860.5396113079535, 860.8278344331159, 861.3582880753847, 862.2153840005627, 628.8365025093462, 629.131798118598, 632.4624069540899, 633.2590868683651, 633.832034510855, 13653.726925461535, 13679.11085779431, 13695.794563081561, 13719.971002174876, 13720.245386503739, 13361.265130417823, 13623.795643065361, 13805.654858628615, 13937.651558711012, 13941.512047078304, 10285.485725713685, 10309.896901031043, 10311.535980880879, 10314.587416503331, 10604.204684648605, 3600.8199590020395, 3632.600413954463, 3659.1766852457986, 3667.0, 3740.756850804689, 57672.42327576754, 73763.63118184375, 75763.0, 75812.0, 75866.62066689631, 11008.174386921013, 11075.833862492069, 11205.831912521318, 11229.831552526719, 11555.248908821068, 17139.828601714074, 17176.31294748196, 17179.7423038652, 17197.7420338695, 17291.221895014754, 7968.521888686697, 8176.581383613953, 8270.586470676442, 8326.167383261625, 8377.246047855722, 4406.27296496081, 4443.2224360736745, 4489.73061616304, 4514.367988481616, 4571.81712731487, 3446.7414943879307, 3530.1351168963256, 3542.734294927891, 3564.7326450515798, 3579.480975258625, 687.9931200688029, 689.2728171778822, 698.1482591238681, 698.4935921456903, 717.1322699533623, 555.1035078348439, 603.8973374526341, 610.9511239100862, 677.3869647968498, 740.7222291640635, 9331.486768227813, 9750.463724495221, 9884.258680598983, 10303.330283531546, 10350.741231469123, 656.8898561431166, 688.4320435640617, 723.9710411583478, 737.3179808676953, 748.1770052941721, 4306.884224401358, 4524.5927866492175, 4549.385832912581, 4596.218642830727, 4704.811807527661, 67820.0, 67969.0, 68110.65944670246, 99466.00533994712, 111752.0, 59826.40173598295, 81908.77136842952, 87132.0, 96802.51598741961, 97564.51217744008, 85927.57036214779, 86807.56596216979, 86984.0, 87127.56436217779, 87311.0, 8251.133630968661, 8274.255317021498, 8317.168283171635, 8502.234798868132, 8762.737117886474, 50701.74649126879, 51804.22293665523, 52629.7368513155, 52839.0, 53739.73130134324, 45583.0, 51159.74420127876, 75324.0, 76113.0, 76263.0, 11539.711507212387, 11599.420028998686, 11726.824097638713, 11879.524819007489, 12183.147179697691, 12025.939870300592, 12124.0, 12479.87520124787, 12734.235945843278, 12734.299613520974, 11499.827502587636, 11510.654680359603, 11755.0, 12023.819642705192, 12132.878671213175, 11258.493367798632, 11602.187846850977, 11693.181477296848, 11774.0, 11816.940915295712, 11709.238899471507, 11720.70698232551, 12025.278483291031, 12375.876241237833, 12410.937945310216, 8710.547090277896, 8954.507502087448, 8973.0, 9280.860787088064, 9572.377795443273, 8742.38803283764, 8947.0, 9082.818343633222, 9098.31762617793, 9185.77035574116], "value": 7032.612039794705, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 111.0, 110.0, 111.0, 111.0, 112.0, 112.0, 110.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 158.0, 177.0, 128.0, 154.0, 172.0, 134.0, 180.0, 126.0], "value": 151.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 163.0, 161.0, 165.0, 162.0, 166.0, 178.0, 162.0, 161.0, 161.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 169.0, 74.0, 76.0, 74.0, 77.0, 157.0, 78.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 147.0, 86.0, 85.0, 90.0, 144.0, 85.0, 85.0, 151.0], "value": 104.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 119.0, 118.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 147.0, 146.0, 149.0, 147.0, 148.0, 147.0, 146.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 98.0, 95.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 59.0, 57.0, 54.0, 60.0, 55.0, 55.0, 54.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 62.0, 65.0, 62.0, 63.0, 62.0, 63.0, 61.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 86.0, 83.0, 80.0, 83.0, 86.0, 80.0, 106.0, 80.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 123.0, 136.0, 129.0, 130.0, 127.0, 131.0, 125.0, 141.0, 139.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 164.0, 160.0, 162.0, 160.0, 162.0, 163.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 67.0, 68.0, 63.0, 63.0, 65.0, 63.0, 61.0, 61.0], "value": 63.9, "unit": "score"}]}]} 22:41:20 INFO - Return code: 0 22:41:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:41:20 ERROR - # TBPL SUCCESS # 22:41:20 INFO - Running post-action listener: _resource_record_post_action 22:41:20 INFO - ##### 22:41:20 INFO - ##### Finished run-tests step (success) 22:41:20 INFO - ##### 22:41:20 INFO - Running post-run listener: _resource_record_post_run 22:41:21 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 541548544; Read time: 20; Write time: 537888 22:41:21 INFO - TinderboxPrint: CPU usage
13.5% 22:41:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 22:41:21 INFO - TinderboxPrint: I/O write bytes / time
541,548,544 / 537,888 22:41:21 INFO - TinderboxPrint: CPU idle
8,219.0 (86.5%) 22:41:21 INFO - TinderboxPrint: CPU user
1,225.1 (12.9%) 22:41:21 INFO - TinderboxPrint: Swap in / out
0 / 0 22:41:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 224878592; Read time: 0; Write time: 300496 22:41:21 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292892672; Read time: 20; Write time: 232948 22:41:21 INFO - Running post-run listener: _upload_blobber_files 22:41:21 INFO - Blob upload gear active. 22:41:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:41:21 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 22:41:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:41:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:41:22 INFO - (blobuploader) - INFO - Open directory for files ... 22:41:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:41:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:41:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:25 INFO - (blobuploader) - INFO - Done attempting. 22:41:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:41:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:41:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:26 INFO - (blobuploader) - INFO - Done attempting. 22:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:27 INFO - (blobuploader) - INFO - Done attempting. 22:41:27 INFO - (blobuploader) - INFO - Iteration through files over. 22:41:27 INFO - Return code: 0 22:41:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:41:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:41:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f7c47d888bfcc5a839b72c73d3b4d68337b211f666084a749c3a744b62c1e94a82757ded9c6c5a15b70429b080cdeb3a8e33df98d0065e6a4b13e78b3cc84afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0313507ab6dc019b24f74b295fe928f128ea9caf6090dd19ce94a8bb87bc0419898f72c8a466d0a5814688b69991e207b1b3d8c256e0d1777dab09bef2494d17", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06daffd84a467af3575bed69e3a6c25b1e9a97fd8d704f394df7723c735fec7fe7bec7221aac54c7a003e91f95ea4616b8e72977983fd71bb5ff92c3ec8ff316"} 22:41:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:41:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:41:27 INFO - Contents: 22:41:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f7c47d888bfcc5a839b72c73d3b4d68337b211f666084a749c3a744b62c1e94a82757ded9c6c5a15b70429b080cdeb3a8e33df98d0065e6a4b13e78b3cc84afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0313507ab6dc019b24f74b295fe928f128ea9caf6090dd19ce94a8bb87bc0419898f72c8a466d0a5814688b69991e207b1b3d8c256e0d1777dab09bef2494d17", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06daffd84a467af3575bed69e3a6c25b1e9a97fd8d704f394df7723c735fec7fe7bec7221aac54c7a003e91f95ea4616b8e72977983fd71bb5ff92c3ec8ff316"} 22:41:27 INFO - Running post-run listener: copy_logs_to_upload_dir 22:41:27 INFO - Copying logs to upload dir... 22:41:27 INFO - mkdir: /builds/slave/test/build/upload/logs 22:41:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.301396 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-06-04 22:41:26.539760) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:26.542909) ========= 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=cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f7c47d888bfcc5a839b72c73d3b4d68337b211f666084a749c3a744b62c1e94a82757ded9c6c5a15b70429b080cdeb3a8e33df98d0065e6a4b13e78b3cc84afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0313507ab6dc019b24f74b295fe928f128ea9caf6090dd19ce94a8bb87bc0419898f72c8a466d0a5814688b69991e207b1b3d8c256e0d1777dab09bef2494d17", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06daffd84a467af3575bed69e3a6c25b1e9a97fd8d704f394df7723c735fec7fe7bec7221aac54c7a003e91f95ea4616b8e72977983fd71bb5ff92c3ec8ff316"} build_url:https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/HJZaV861R9Wz4UJT1ZBSHw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f7c47d888bfcc5a839b72c73d3b4d68337b211f666084a749c3a744b62c1e94a82757ded9c6c5a15b70429b080cdeb3a8e33df98d0065e6a4b13e78b3cc84afb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0313507ab6dc019b24f74b295fe928f128ea9caf6090dd19ce94a8bb87bc0419898f72c8a466d0a5814688b69991e207b1b3d8c256e0d1777dab09bef2494d17", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/06daffd84a467af3575bed69e3a6c25b1e9a97fd8d704f394df7723c735fec7fe7bec7221aac54c7a003e91f95ea4616b8e72977983fd71bb5ff92c3ec8ff316"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:26.567847) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:26.568169) ========= 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=cbe409c6de289db6ac707de3000001d4-1465083016.572728-455747762 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 22:41:26.655783) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:41:26.656097) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 22:41:26.656393) ========= ========= Total master_lag: 0.17 =========