builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1466507373.4 results: success (0) buildid: 20160621025705 builduid: 94301783bf474628bea94b579f25e10f revision: 0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.404674) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.405114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.405387) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.422042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.422394) ========= 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=5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-21 04:09:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-06-21 04:09:33 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098776 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.535060) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.535370) ========= 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=5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022646 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.588292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:33.591718) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1 --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=5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-21 04:09:33,608 truncating revision to first 12 chars 2016-06-21 04:09:33,608 Setting DEBUG logging. 2016-06-21 04:09:33,608 attempt 1/10 2016-06-21 04:09:33,608 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0ffa18cfc8b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 04:09:33,998 unpacking tar archive at: mozilla-central-0ffa18cfc8b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.735136 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:34.340245) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:34.340562) ========= script_repo_revision: 0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:34.340936) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:34.341199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 04:09:34.352285) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-06-21 04:09:34.352672) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:09:34 INFO - MultiFileLogger online at 20160621 04:09:34 in /builds/slave/test 04:09:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:09:34 INFO - {'append_to_log': False, 04:09:34 INFO - 'base_work_dir': '/builds/slave/test', 04:09:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:09:34 INFO - 'branch': 'Firefox-Non-PGO', 04:09:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:09:34 INFO - 'config_files': ('talos/linux_config.py',), 04:09:34 INFO - 'default_actions': ('clobber', 04:09:34 INFO - 'read-buildbot-config', 04:09:34 INFO - 'download-and-extract', 04:09:34 INFO - 'populate-webroot', 04:09:34 INFO - 'create-virtualenv', 04:09:34 INFO - 'install', 04:09:34 INFO - 'run-tests'), 04:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:09:34 INFO - 'download_minidump_stackwalk': True, 04:09:34 INFO - 'download_symbols': 'ondemand', 04:09:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:09:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:09:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:09:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:09:34 INFO - 'installer_path': 'installer.exe', 04:09:34 INFO - 'log_level': 'info', 04:09:34 INFO - 'log_name': 'talos', 04:09:34 INFO - 'log_to_console': True, 04:09:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:09:34 INFO - 'opt_config_files': (), 04:09:34 INFO - 'pip_index': False, 04:09:34 INFO - 'sps_profile': False, 04:09:34 INFO - 'sps_profile_interval': 0, 04:09:34 INFO - 'suite': 'dromaeojs', 04:09:34 INFO - 'system_bits': '32', 04:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:09:34 INFO - 'title': 'talos-linux64-ix-011', 04:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:09:34 INFO - 'use_talos_json': True, 04:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:09:34 INFO - 'work_dir': 'build'} 04:09:34 INFO - ##### 04:09:34 INFO - ##### Running clobber step. 04:09:34 INFO - ##### 04:09:34 INFO - Running pre-action listener: _resource_record_pre_action 04:09:34 INFO - Running main action method: clobber 04:09:34 INFO - rmtree: /builds/slave/test/build 04:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:09:35 INFO - Running post-action listener: _resource_record_post_action 04:09:35 INFO - ##### 04:09:35 INFO - ##### Finished clobber step (success) 04:09:35 INFO - ##### 04:09:35 INFO - ##### 04:09:35 INFO - ##### Running read-buildbot-config step. 04:09:35 INFO - ##### 04:09:35 INFO - Running pre-action listener: _resource_record_pre_action 04:09:35 INFO - Running main action method: read_buildbot_config 04:09:35 INFO - Using buildbot properties: 04:09:35 INFO - { 04:09:35 INFO - "project": "", 04:09:35 INFO - "product": "firefox", 04:09:35 INFO - "script_repo_revision": "production", 04:09:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:09:35 INFO - "repository": "", 04:09:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 04:09:35 INFO - "buildid": "20160621025705", 04:09:35 INFO - "pgo_build": "False", 04:09:35 INFO - "basedir": "/builds/slave/test", 04:09:35 INFO - "buildnumber": 613, 04:09:35 INFO - "slavename": "talos-linux64-ix-011", 04:09:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:09:35 INFO - "platform": "ubuntu64_hw", 04:09:35 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:09:35 INFO - "branch": "mozilla-central", 04:09:35 INFO - "repo_path": "mozilla-central", 04:09:35 INFO - "revision": "0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1", 04:09:35 INFO - "stage_platform": "linux64", 04:09:35 INFO - "builduid": "94301783bf474628bea94b579f25e10f", 04:09:35 INFO - "slavebuilddir": "test" 04:09:35 INFO - } 04:09:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:09:35 INFO - Running post-action listener: _resource_record_post_action 04:09:35 INFO - ##### 04:09:35 INFO - ##### Finished read-buildbot-config step (success) 04:09:35 INFO - ##### 04:09:35 INFO - ##### 04:09:35 INFO - ##### Running download-and-extract step. 04:09:35 INFO - ##### 04:09:35 INFO - Running pre-action listener: _resource_record_pre_action 04:09:35 INFO - Running main action method: download_and_extract 04:09:35 INFO - mkdir: /builds/slave/test/build/tests 04:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:35 INFO - https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:09:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:09:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:09:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:09:36 INFO - Downloaded 1361 bytes. 04:09:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:09:36 INFO - Using the following test package requirements: 04:09:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:09:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:09:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:09:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'jsshell-linux-x86_64.zip'], 04:09:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:09:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:09:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:09:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:09:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:09:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:09:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:09:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:09:36 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')]} 04:09:36 INFO - https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:09:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:09:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:09:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:09:38 INFO - Downloaded 22658694 bytes. 04:09:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:09:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:09:39 INFO - Return code: 0 04:09:39 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:39 INFO - https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:09:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:09:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:09:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:09:41 INFO - Downloaded 22658694 bytes. 04:09:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:09:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:09:43 INFO - Return code: 0 04:09:43 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')]} 04:09:43 INFO - https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:09:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:09:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:09:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:09:45 INFO - Downloaded 14405384 bytes. 04:09:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:09:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:09:45 INFO - Return code: 0 04:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:45 INFO - https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:09:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:09:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:09:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:09:47 INFO - Downloaded 56228048 bytes. 04:09:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:09:47 INFO - mkdir: /builds/slave/test/properties 04:09:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:47 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:47 INFO - Contents: 04:09:47 INFO - build_url:https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:09:48 INFO - Running post-action listener: _resource_record_post_action 04:09:48 INFO - Running post-action listener: set_extra_try_arguments 04:09:48 INFO - ##### 04:09:48 INFO - ##### Finished download-and-extract step (success) 04:09:48 INFO - ##### 04:09:48 INFO - ##### 04:09:48 INFO - ##### Running populate-webroot step. 04:09:48 INFO - ##### 04:09:48 INFO - Running pre-action listener: _resource_record_pre_action 04:09:48 INFO - Running main action method: populate_webroot 04:09:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:09:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:09:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:09:48 INFO - '--tppagecycles', 04:09:48 INFO - '7'], 04:09:48 INFO - 'tests': ['tsvgm']}}, 04:09:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:09:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:09:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:09:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:09:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['damp', 'tps']}, 04:09:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'tests': ['damp', 'tps']}, 04:09:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['dromaeo_dom']}, 04:09:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:09:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['basic_compositor_video']}, 04:09:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:09:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['a11yr', 04:09:48 INFO - 'ts_paint', 04:09:48 INFO - 'tpaint', 04:09:48 INFO - 'sessionrestore', 04:09:48 INFO - 'sessionrestore_no_auto_restore', 04:09:48 INFO - 'tabpaint']}, 04:09:48 INFO - 'other-e10s': {'tests': ['a11yr', 04:09:48 INFO - 'ts_paint', 04:09:48 INFO - 'tpaint', 04:09:48 INFO - 'sessionrestore', 04:09:48 INFO - 'sessionrestore_no_auto_restore', 04:09:48 INFO - 'tabpaint']}, 04:09:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:09:48 INFO - 'ts_paint', 04:09:48 INFO - 'tpaint', 04:09:48 INFO - 'sessionrestore', 04:09:48 INFO - 'sessionrestore_no_auto_restore', 04:09:48 INFO - 'tabpaint']}, 04:09:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:09:48 INFO - 'ts_paint', 04:09:48 INFO - 'tpaint', 04:09:48 INFO - 'sessionrestore', 04:09:48 INFO - 'sessionrestore_no_auto_restore', 04:09:48 INFO - 'tabpaint']}, 04:09:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['a11yr', 04:09:48 INFO - 'ts_paint', 04:09:48 INFO - 'tpaint', 04:09:48 INFO - 'sessionrestore', 04:09:48 INFO - 'sessionrestore_no_auto_restore', 04:09:48 INFO - 'tabpaint']}, 04:09:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['a11yr', 04:09:48 INFO - 'ts_paint', 04:09:48 INFO - 'tpaint', 04:09:48 INFO - 'sessionrestore', 04:09:48 INFO - 'sessionrestore_no_auto_restore', 04:09:48 INFO - 'tabpaint']}, 04:09:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['tsvgx', 04:09:48 INFO - 'tsvgr_opacity', 04:09:48 INFO - 'tart', 04:09:48 INFO - 'tscrollx', 04:09:48 INFO - 'cart']}, 04:09:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:09:48 INFO - 'tsvgr_opacity', 04:09:48 INFO - 'tart', 04:09:48 INFO - 'tscrollx', 04:09:48 INFO - 'cart']}, 04:09:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'talos_options': ['--disable-e10s'], 04:09:48 INFO - 'tests': ['tp5o']}, 04:09:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'tests': ['tp5o']}, 04:09:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'talos_options': ['--disable-e10s', 04:09:48 INFO - '--xperf_path', 04:09:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:48 INFO - 'tests': ['tp5n']}, 04:09:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:48 INFO - 'talos_options': ['--xperf_path', 04:09:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:48 INFO - 'tests': ['tp5n']}}, 04:09:48 INFO - 'talos.zip': {'path': '', 04:09:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:09:48 INFO - Running post-action listener: _resource_record_post_action 04:09:48 INFO - ##### 04:09:48 INFO - ##### Finished populate-webroot step (success) 04:09:48 INFO - ##### 04:09:48 INFO - ##### 04:09:48 INFO - ##### Running create-virtualenv step. 04:09:48 INFO - ##### 04:09:48 INFO - Running pre-action listener: _resource_record_pre_action 04:09:48 INFO - Running main action method: create_virtualenv 04:09:48 INFO - Creating virtualenv /builds/slave/test/build/venv 04:09:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:09:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:09:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:09:48 INFO - Using real prefix '/usr' 04:09:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:09:48 INFO - Installing distribute.............................................................................................................................................................................................done. 04:09:50 INFO - Installing pip.................done. 04:09:50 INFO - Return code: 0 04:09:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:09:50 INFO - Using env: {'DISPLAY': ':0', 04:09:50 INFO - 'HOME': '/home/cltbld', 04:09:50 INFO - 'LANG': 'en_US.UTF-8', 04:09:50 INFO - 'LANGUAGE': 'en_US:en', 04:09:50 INFO - 'LOGNAME': 'cltbld', 04:09:50 INFO - 'MAIL': '/var/mail/cltbld', 04:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:50 INFO - 'MOZ_NO_REMOTE': '1', 04:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:50 INFO - 'NO_EM_RESTART': '1', 04:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:50 INFO - 'PWD': '/builds/slave/test', 04:09:50 INFO - 'SHELL': '/bin/bash', 04:09:50 INFO - 'SHLVL': '1', 04:09:50 INFO - 'TERM': 'linux', 04:09:50 INFO - 'TMOUT': '86400', 04:09:50 INFO - 'USER': 'cltbld', 04:09:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:50 INFO - '_': '/tools/buildbot/bin/python'} 04:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:09:51 INFO - Cleaning up... 04:09:51 INFO - Return code: 0 04:09:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:09:51 INFO - Using env: {'DISPLAY': ':0', 04:09:51 INFO - 'HOME': '/home/cltbld', 04:09:51 INFO - 'LANG': 'en_US.UTF-8', 04:09:51 INFO - 'LANGUAGE': 'en_US:en', 04:09:51 INFO - 'LOGNAME': 'cltbld', 04:09:51 INFO - 'MAIL': '/var/mail/cltbld', 04:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:51 INFO - 'MOZ_NO_REMOTE': '1', 04:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:51 INFO - 'NO_EM_RESTART': '1', 04:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:51 INFO - 'PWD': '/builds/slave/test', 04:09:51 INFO - 'SHELL': '/bin/bash', 04:09:51 INFO - 'SHLVL': '1', 04:09:51 INFO - 'TERM': 'linux', 04:09:51 INFO - 'TMOUT': '86400', 04:09:51 INFO - 'USER': 'cltbld', 04:09:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:51 INFO - '_': '/tools/buildbot/bin/python'} 04:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:51 INFO - Downloading/unpacking psutil>=3.1.1 04:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:09:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:53 INFO - Installing collected packages: psutil 04:09:53 INFO - Running setup.py install for psutil 04:09:53 INFO - building 'psutil._psutil_linux' extension 04:09:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:09:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:09:53 INFO - building 'psutil._psutil_posix' extension 04:09:53 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 04:09:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:09:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:53 INFO - Successfully installed psutil 04:09:53 INFO - Cleaning up... 04:09:54 INFO - Return code: 0 04:09:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:09:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:09:54 INFO - Using env: {'DISPLAY': ':0', 04:09:54 INFO - 'HOME': '/home/cltbld', 04:09:54 INFO - 'LANG': 'en_US.UTF-8', 04:09:54 INFO - 'LANGUAGE': 'en_US:en', 04:09:54 INFO - 'LOGNAME': 'cltbld', 04:09:54 INFO - 'MAIL': '/var/mail/cltbld', 04:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:54 INFO - 'MOZ_NO_REMOTE': '1', 04:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:54 INFO - 'NO_EM_RESTART': '1', 04:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:54 INFO - 'PWD': '/builds/slave/test', 04:09:54 INFO - 'SHELL': '/bin/bash', 04:09:54 INFO - 'SHLVL': '1', 04:09:54 INFO - 'TERM': 'linux', 04:09:54 INFO - 'TMOUT': '86400', 04:09:54 INFO - 'USER': 'cltbld', 04:09:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:54 INFO - '_': '/tools/buildbot/bin/python'} 04:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:09:56 INFO - Installing collected packages: mozsystemmonitor 04:09:56 INFO - Running setup.py install for mozsystemmonitor 04:09:56 INFO - Successfully installed mozsystemmonitor 04:09:56 INFO - Cleaning up... 04:09:56 INFO - Return code: 0 04:09:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:09:56 INFO - Using env: {'DISPLAY': ':0', 04:09:56 INFO - 'HOME': '/home/cltbld', 04:09:56 INFO - 'LANG': 'en_US.UTF-8', 04:09:56 INFO - 'LANGUAGE': 'en_US:en', 04:09:56 INFO - 'LOGNAME': 'cltbld', 04:09:56 INFO - 'MAIL': '/var/mail/cltbld', 04:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:56 INFO - 'MOZ_NO_REMOTE': '1', 04:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:56 INFO - 'NO_EM_RESTART': '1', 04:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:56 INFO - 'PWD': '/builds/slave/test', 04:09:56 INFO - 'SHELL': '/bin/bash', 04:09:56 INFO - 'SHLVL': '1', 04:09:56 INFO - 'TERM': 'linux', 04:09:56 INFO - 'TMOUT': '86400', 04:09:56 INFO - 'USER': 'cltbld', 04:09:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:56 INFO - '_': '/tools/buildbot/bin/python'} 04:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:57 INFO - Downloading/unpacking jsonschema==2.5.1 04:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:59 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:59 INFO - warning: no files found matching '*.txt' 04:09:59 INFO - no previously-included directories found matching 'build' 04:09:59 INFO - no previously-included directories found matching 'dist' 04:09:59 INFO - no previously-included directories found matching '.git*' 04:09:59 INFO - Installing collected packages: jsonschema, functools32 04:09:59 INFO - Running setup.py install for functools32 04:09:59 INFO - warning: no files found matching '*.txt' 04:09:59 INFO - no previously-included directories found matching 'build' 04:09:59 INFO - no previously-included directories found matching 'dist' 04:09:59 INFO - no previously-included directories found matching '.git*' 04:09:59 INFO - Successfully installed jsonschema functools32 04:09:59 INFO - Cleaning up... 04:09:59 INFO - Return code: 0 04:09:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:09:59 INFO - Using env: {'DISPLAY': ':0', 04:09:59 INFO - 'HOME': '/home/cltbld', 04:09:59 INFO - 'LANG': 'en_US.UTF-8', 04:09:59 INFO - 'LANGUAGE': 'en_US:en', 04:09:59 INFO - 'LOGNAME': 'cltbld', 04:09:59 INFO - 'MAIL': '/var/mail/cltbld', 04:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:59 INFO - 'MOZ_NO_REMOTE': '1', 04:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:59 INFO - 'NO_EM_RESTART': '1', 04:09:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:59 INFO - 'PWD': '/builds/slave/test', 04:09:59 INFO - 'SHELL': '/bin/bash', 04:09:59 INFO - 'SHLVL': '1', 04:09:59 INFO - 'TERM': 'linux', 04:09:59 INFO - 'TMOUT': '86400', 04:09:59 INFO - 'USER': 'cltbld', 04:09:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:59 INFO - '_': '/tools/buildbot/bin/python'} 04:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:00 INFO - Downloading/unpacking blobuploader==1.2.4 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:02 INFO - Downloading blobuploader-1.2.4.tar.gz 04:10:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:10:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:10:02 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 04:10:02 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 04:10:02 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 04:10:02 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 04:10:02 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 04:10:02 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 04:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:10:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:03 INFO - Downloading docopt-0.6.1.tar.gz 04:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:10:03 INFO - Installing collected packages: blobuploader, requests, docopt 04:10:03 INFO - Running setup.py install for blobuploader 04:10:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:10:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:10:03 INFO - Running setup.py install for requests 04:10:04 INFO - Running setup.py install for docopt 04:10:04 INFO - Successfully installed blobuploader requests docopt 04:10:04 INFO - Cleaning up... 04:10:04 INFO - Return code: 0 04:10:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:04 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')]} 04:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:04 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')]} 04:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:04 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 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:10:04 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 04:10:04 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 04:10:04 INFO - Using env: {'DISPLAY': ':0', 04:10:04 INFO - 'HOME': '/home/cltbld', 04:10:04 INFO - 'LANG': 'en_US.UTF-8', 04:10:04 INFO - 'LANGUAGE': 'en_US:en', 04:10:04 INFO - 'LOGNAME': 'cltbld', 04:10:04 INFO - 'MAIL': '/var/mail/cltbld', 04:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:04 INFO - 'MOZ_NO_REMOTE': '1', 04:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:04 INFO - 'NO_EM_RESTART': '1', 04:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:04 INFO - 'PWD': '/builds/slave/test', 04:10:04 INFO - 'SHELL': '/bin/bash', 04:10:04 INFO - 'SHLVL': '1', 04:10:04 INFO - 'TERM': 'linux', 04:10:04 INFO - 'TMOUT': '86400', 04:10:04 INFO - 'USER': 'cltbld', 04:10:04 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:04 INFO - '_': '/tools/buildbot/bin/python'} 04:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:04 INFO - Running setup.py (path:/tmp/pip-xLbOZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:04 INFO - Running setup.py (path:/tmp/pip-EjmYYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:04 INFO - Running setup.py (path:/tmp/pip-EYqIaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:04 INFO - Running setup.py (path:/tmp/pip-wz3gkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:04 INFO - Running setup.py (path:/tmp/pip-7Hp3O7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:04 INFO - Running setup.py (path:/tmp/pip-JDJQfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:04 INFO - Running setup.py (path:/tmp/pip-OHhdUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:04 INFO - Running setup.py (path:/tmp/pip-hnEmhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:04 INFO - Running setup.py (path:/tmp/pip-ZsENRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:05 INFO - Running setup.py (path:/tmp/pip-axqubM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:05 INFO - Running setup.py (path:/tmp/pip-RduOXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:05 INFO - Running setup.py (path:/tmp/pip-sHhiuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:05 INFO - Running setup.py (path:/tmp/pip-muepgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:05 INFO - Running setup.py (path:/tmp/pip-1Ga7jC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:05 INFO - Running setup.py (path:/tmp/pip-QTB9lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:05 INFO - Running setup.py (path:/tmp/pip-RrE_Sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:05 INFO - Running setup.py (path:/tmp/pip-KTEsqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:10:05 INFO - Running setup.py install for manifestparser 04:10:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:10:05 INFO - Running setup.py install for mozcrash 04:10:05 INFO - Running setup.py install for mozdebug 04:10:05 INFO - Running setup.py install for mozdevice 04:10:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for mozfile 04:10:06 INFO - Running setup.py install for mozhttpd 04:10:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for mozinfo 04:10:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for mozInstall 04:10:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for mozleak 04:10:06 INFO - Running setup.py install for mozlog 04:10:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for moznetwork 04:10:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for mozprocess 04:10:06 INFO - Running setup.py install for mozprofile 04:10:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:10:06 INFO - Running setup.py install for mozrunner 04:10:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:10:07 INFO - Running setup.py install for mozscreenshot 04:10:07 INFO - Running setup.py install for moztest 04:10:07 INFO - Running setup.py install for mozversion 04:10:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:10:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:10:07 INFO - Cleaning up... 04:10:07 INFO - Return code: 0 04:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10: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')]} 04:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10: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')]} 04:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:07 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 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:10:07 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 04:10:07 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 04:10:07 INFO - Using env: {'DISPLAY': ':0', 04:10:07 INFO - 'HOME': '/home/cltbld', 04:10:07 INFO - 'LANG': 'en_US.UTF-8', 04:10:07 INFO - 'LANGUAGE': 'en_US:en', 04:10:07 INFO - 'LOGNAME': 'cltbld', 04:10:07 INFO - 'MAIL': '/var/mail/cltbld', 04:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:07 INFO - 'MOZ_NO_REMOTE': '1', 04:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:07 INFO - 'NO_EM_RESTART': '1', 04:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:07 INFO - 'PWD': '/builds/slave/test', 04:10:07 INFO - 'SHELL': '/bin/bash', 04:10:07 INFO - 'SHLVL': '1', 04:10:07 INFO - 'TERM': 'linux', 04:10:07 INFO - 'TMOUT': '86400', 04:10:07 INFO - 'USER': 'cltbld', 04:10:07 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:07 INFO - '_': '/tools/buildbot/bin/python'} 04:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:07 INFO - Running setup.py (path:/tmp/pip-UWCdjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:07 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)) 04:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:07 INFO - Running setup.py (path:/tmp/pip-W9mwot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:07 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)) 04:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:07 INFO - Running setup.py (path:/tmp/pip-KzmHxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:07 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)) 04:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:07 INFO - Running setup.py (path:/tmp/pip-NPxFz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:07 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)) 04:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:07 INFO - Running setup.py (path:/tmp/pip-HQoAzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:07 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)) 04:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:08 INFO - Running setup.py (path:/tmp/pip-BIgRwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:08 INFO - Running setup.py (path:/tmp/pip-T6ozk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:08 INFO - Running setup.py (path:/tmp/pip-0LoEzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:08 INFO - Running setup.py (path:/tmp/pip-UwbTvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:08 INFO - Running setup.py (path:/tmp/pip-me8y1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:08 INFO - Running setup.py (path:/tmp/pip-D72wnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:08 INFO - Running setup.py (path:/tmp/pip-NWk0Cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:08 INFO - Running setup.py (path:/tmp/pip-rzmRWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:08 INFO - Running setup.py (path:/tmp/pip-mjhcdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:08 INFO - Running setup.py (path:/tmp/pip-_yL7Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:08 INFO - Running setup.py (path:/tmp/pip-7woXv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:08 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)) 04:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:08 INFO - Running setup.py (path:/tmp/pip-gKSaSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:08 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)) 04:10:08 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)) 04:10:08 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)) 04:10:08 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)) 04:10:08 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)) 04:10:08 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)) 04:10:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10:10 INFO - Downloading blessings-1.6.tar.gz 04:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:10:10 INFO - Installing collected packages: blessings 04:10:10 INFO - Running setup.py install for blessings 04:10:10 INFO - Successfully installed blessings 04:10:10 INFO - Cleaning up... 04:10:11 INFO - Return code: 0 04:10:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:10:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:11 INFO - Reading from file tmpfile_stdout 04:10:11 INFO - Current package versions: 04:10:11 INFO - argparse == 1.2.1 04:10:11 INFO - blessings == 1.6 04:10:11 INFO - blobuploader == 1.2.4 04:10:11 INFO - docopt == 0.6.1 04:10:11 INFO - functools32 == 3.2.3-2 04:10:11 INFO - jsonschema == 2.5.1 04:10:11 INFO - manifestparser == 1.1 04:10:11 INFO - mozInstall == 1.12 04:10:11 INFO - mozcrash == 0.17 04:10:11 INFO - mozdebug == 0.1 04:10:11 INFO - mozdevice == 0.48 04:10:11 INFO - mozfile == 1.2 04:10:11 INFO - mozhttpd == 0.7 04:10:11 INFO - mozinfo == 0.9 04:10:11 INFO - mozleak == 0.1 04:10:11 INFO - mozlog == 3.2 04:10:11 INFO - moznetwork == 0.27 04:10:11 INFO - mozprocess == 0.22 04:10:11 INFO - mozprofile == 0.28 04:10:11 INFO - mozrunner == 6.11 04:10:11 INFO - mozscreenshot == 0.1 04:10:11 INFO - mozsystemmonitor == 0.3 04:10:11 INFO - moztest == 0.7 04:10:11 INFO - mozversion == 1.4 04:10:11 INFO - psutil == 3.1.1 04:10:11 INFO - requests == 1.2.3 04:10:11 INFO - wsgiref == 0.1.2 04:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:11 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')]} 04:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:11 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')]} 04:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:11 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 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:10:11 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 04:10:11 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 04:10:11 INFO - Using env: {'DISPLAY': ':0', 04:10:11 INFO - 'HOME': '/home/cltbld', 04:10:11 INFO - 'LANG': 'en_US.UTF-8', 04:10:11 INFO - 'LANGUAGE': 'en_US:en', 04:10:11 INFO - 'LOGNAME': 'cltbld', 04:10:11 INFO - 'MAIL': '/var/mail/cltbld', 04:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:11 INFO - 'MOZ_NO_REMOTE': '1', 04:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:11 INFO - 'NO_EM_RESTART': '1', 04:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:11 INFO - 'PWD': '/builds/slave/test', 04:10:11 INFO - 'SHELL': '/bin/bash', 04:10:11 INFO - 'SHLVL': '1', 04:10:11 INFO - 'TERM': 'linux', 04:10:11 INFO - 'TMOUT': '86400', 04:10:11 INFO - 'USER': 'cltbld', 04:10:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:11 INFO - '_': '/tools/buildbot/bin/python'} 04:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 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)) 04:10:11 INFO - Cleaning up... 04:10:11 INFO - Return code: 0 04:10:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:10:11 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')]} 04:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:11 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')]} 04:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:11 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 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:10:11 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 04:10:11 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 04:10:11 INFO - Using env: {'DISPLAY': ':0', 04:10:11 INFO - 'HOME': '/home/cltbld', 04:10:11 INFO - 'LANG': 'en_US.UTF-8', 04:10:11 INFO - 'LANGUAGE': 'en_US:en', 04:10:11 INFO - 'LOGNAME': 'cltbld', 04:10:11 INFO - 'MAIL': '/var/mail/cltbld', 04:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:11 INFO - 'MOZ_NO_REMOTE': '1', 04:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:11 INFO - 'NO_EM_RESTART': '1', 04:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:11 INFO - 'PWD': '/builds/slave/test', 04:10:11 INFO - 'SHELL': '/bin/bash', 04:10:11 INFO - 'SHLVL': '1', 04:10:11 INFO - 'TERM': 'linux', 04:10:11 INFO - 'TMOUT': '86400', 04:10:11 INFO - 'USER': 'cltbld', 04:10:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:11 INFO - '_': '/tools/buildbot/bin/python'} 04:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:10:11 INFO - Cleaning up... 04:10:11 INFO - Return code: 0 04:10:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:10:11 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')]} 04:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:11 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')]} 04:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:11 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 0x7fe58ff9df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2490350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, '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': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', '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 04:10:11 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 04:10:11 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 04:10:11 INFO - Using env: {'DISPLAY': ':0', 04:10:11 INFO - 'HOME': '/home/cltbld', 04:10:11 INFO - 'LANG': 'en_US.UTF-8', 04:10:11 INFO - 'LANGUAGE': 'en_US:en', 04:10:11 INFO - 'LOGNAME': 'cltbld', 04:10:11 INFO - 'MAIL': '/var/mail/cltbld', 04:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:11 INFO - 'MOZ_NO_REMOTE': '1', 04:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:11 INFO - 'NO_EM_RESTART': '1', 04:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:11 INFO - 'PWD': '/builds/slave/test', 04:10:11 INFO - 'SHELL': '/bin/bash', 04:10:11 INFO - 'SHLVL': '1', 04:10:11 INFO - 'TERM': 'linux', 04:10:11 INFO - 'TMOUT': '86400', 04:10:11 INFO - 'USER': 'cltbld', 04:10:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:11 INFO - '_': '/tools/buildbot/bin/python'} 04:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:11 INFO - Downloading/unpacking flake8 04:10:11 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 04:10:11 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 04:10:11 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 04:10:11 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 04:10:11 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 04:10:11 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 04:10:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:10:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:10:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 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 04:10:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:14 INFO - no previously-included directories found matching 'docs/_build' 04:10:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:10:14 INFO - Running setup.py install for pep8 04:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:14 INFO - no previously-included directories found matching 'docs/_build' 04:10:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:10:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:10:14 INFO - Cleaning up... 04:10:14 INFO - Return code: 0 04:10:14 INFO - Running post-action listener: _resource_record_post_action 04:10:14 INFO - Running post-action listener: _start_resource_monitoring 04:10:14 INFO - Starting resource monitoring. 04:10:14 INFO - ##### 04:10:14 INFO - ##### Finished create-virtualenv step (success) 04:10:14 INFO - ##### 04:10:14 INFO - ##### 04:10:14 INFO - ##### Running install step. 04:10:14 INFO - ##### 04:10:14 INFO - Running pre-action listener: _resource_record_pre_action 04:10:14 INFO - Running main action method: install 04:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:15 INFO - Reading from file tmpfile_stdout 04:10:15 INFO - Detecting whether we're running mozinstall >=1.0... 04:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:10:15 INFO - Reading from file tmpfile_stdout 04:10:15 INFO - Output received: 04:10:15 INFO - Usage: mozinstall [options] installer 04:10:15 INFO - Options: 04:10:15 INFO - -h, --help show this help message and exit 04:10:15 INFO - -d DEST, --destination=DEST 04:10:15 INFO - Directory to install application into. [default: 04:10:15 INFO - "/builds/slave/test"] 04:10:15 INFO - --app=APP Application being installed. [default: firefox] 04:10:15 INFO - mkdir: /builds/slave/test/build/application 04:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:10:31 INFO - Reading from file tmpfile_stdout 04:10:31 INFO - Output received: 04:10:31 INFO - /builds/slave/test/build/application/firefox/firefox 04:10:31 INFO - Running post-action listener: _resource_record_post_action 04:10:31 INFO - ##### 04:10:31 INFO - ##### Finished install step (success) 04:10:31 INFO - ##### 04:10:31 INFO - ##### 04:10:31 INFO - ##### Running run-tests step. 04:10:31 INFO - ##### 04:10:31 INFO - Running pre-action listener: _resource_record_pre_action 04:10:31 INFO - Running main action method: run_tests 04:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:10:31 INFO - Python 2.7.3 04:10:31 INFO - Return code: 0 04:10:31 INFO - grabbing minidump binary from tooltool 04:10:31 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')]} 04:10:31 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 0x251f1c0>, '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 0x25200c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2520550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:10:31 INFO - Return code: 0 04:10:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:10:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:10:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:10:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:10:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:10:32 INFO - Return code: 0 04:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:10:32 INFO - Using env: {'DISPLAY': ':0', 04:10:32 INFO - 'HOME': '/home/cltbld', 04:10:32 INFO - 'LANG': 'en_US.UTF-8', 04:10:32 INFO - 'LANGUAGE': 'en_US:en', 04:10:32 INFO - 'LOGNAME': 'cltbld', 04:10:32 INFO - 'MAIL': '/var/mail/cltbld', 04:10:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:10:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:32 INFO - 'MOZ_NO_REMOTE': '1', 04:10:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:32 INFO - 'NO_EM_RESTART': '1', 04:10:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:32 INFO - 'PWD': '/builds/slave/test', 04:10:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:10:32 INFO - 'SHELL': '/bin/bash', 04:10:32 INFO - 'SHLVL': '1', 04:10:32 INFO - 'TERM': 'linux', 04:10:32 INFO - 'TMOUT': '86400', 04:10:32 INFO - 'USER': 'cltbld', 04:10:32 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580', 04:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:32 INFO - '_': '/tools/buildbot/bin/python'} 04:10:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:10:32 INFO - mozversion application_buildid: 20160621025705 04:10:32 INFO - mozversion application_changeset: 0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1 04:10:32 INFO - mozversion application_display_name: Nightly 04:10:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:10:32 INFO - mozversion application_name: Firefox 04:10:32 INFO - mozversion application_remotingname: firefox 04:10:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:10:32 INFO - mozversion application_vendor: Mozilla 04:10:32 INFO - mozversion application_version: 50.0a1 04:10:32 INFO - mozversion platform_buildid: 20160621025705 04:10:32 INFO - mozversion platform_changeset: 0ffa18cfc8b4e9970275ab6dd94686792a5c8ae1 04:10:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:10:32 INFO - mozversion platform_version: 50.0a1 04:10:32 INFO - using testdate: 1466507432 04:10:32 INFO - actual date: 1466507432 04:10:32 INFO - starting webserver on 'localhost:56039' 04:10:32 INFO - SUITE-START | Running 2 tests 04:10:32 INFO - TEST-START | dromaeo_css 04:10:32 INFO - operating with platform_type : linux_ 04:10:32 INFO - Initialising browser for dromaeo_css test... 04:10:32 INFO - TEST-INFO | started process 29796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx9YIcc/profile http://localhost:56039/getInfo.html) 04:10:38 INFO - PROCESS | 29796 | __metrics Screen width/height:1600/1200 04:10:38 INFO - PROCESS | 29796 | colorDepth:24 04:10:38 INFO - PROCESS | 29796 | Browser inner width/height: 1024/697 04:10:38 INFO - PROCESS | 29796 | __metrics 04:10:40 INFO - PROCESS | 29796 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:10:41 INFO - TEST-INFO | 29796: exit 0 04:10:41 INFO - Browser initialized. 04:10:41 INFO - Running cycle 1/1 for dromaeo_css test... 04:10:41 INFO - TEST-INFO | started process 29878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx9YIcc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:10:53 INFO - PROCESS | 29878 | RSS: Main: 175919104 04:10:53 INFO - PROCESS | 29878 | 04:11:45 INFO - PROCESS | 29878 | 1466507505303 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] 04:13:58 INFO - PROCESS | 29878 | Cycle 1(1): loaded http://localhost:56039/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56039/tests/dromaeo/cssquery-ext.html) 04:13:59 INFO - PROCESS | 29878 | RSS: Main: 283742208 04:13:59 INFO - PROCESS | 29878 | 04:17:01 INFO - PROCESS | 29878 | Cycle 1(1): loaded http://localhost:56039/tests/dromaeo/cssquery-ext.html (next: http://localhost:56039/tests/dromaeo/cssquery-jquery.html) 04:17:02 INFO - PROCESS | 29878 | RSS: Main: 254910464 04:17:02 INFO - PROCESS | 29878 | 04:20:04 INFO - PROCESS | 29878 | Cycle 1(1): loaded http://localhost:56039/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56039/tests/dromaeo/cssquery-mootools.html) 04:20:05 INFO - PROCESS | 29878 | RSS: Main: 256458752 04:20:05 INFO - PROCESS | 29878 | 04:23:07 INFO - PROCESS | 29878 | Cycle 1(1): loaded http://localhost:56039/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56039/tests/dromaeo/cssquery-prototype.html) 04:23:07 INFO - PROCESS | 29878 | RSS: Main: 250171392 04:23:07 INFO - PROCESS | 29878 | 04:26:10 INFO - PROCESS | 29878 | Cycle 1(1): loaded http://localhost:56039/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56039/tests/dromaeo/cssquery-yui.html) 04:26:11 INFO - PROCESS | 29878 | RSS: Main: 233533440 04:26:11 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | Cycle 1(1): loaded http://localhost:56039/tests/dromaeo/cssquery-yui.html (next: http://localhost:56039/tests/dromaeo/cssquery-dojo.html) 04:29:14 INFO - PROCESS | 29878 | RSS: Main: 278446080 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | __start_tp_report 04:29:14 INFO - PROCESS | 29878 | _x_x_mozilla_page_load 04:29:14 INFO - PROCESS | 29878 | _x_x_mozilla_page_load_details 04:29:14 INFO - PROCESS | 29878 | |i|pagename|runs| 04:29:14 INFO - PROCESS | 29878 | |0;dojo.html;2596.974195192899;2618.869056547172;2647.669041369829;2657.14971209213;2664.4404675018245;4469.642428605712;4547.727136371812;4585.151746926821;4660.487346391898;4706.270528068147;4528.7282763034245;4535.455745310559;4553.88615284618;4566.497685254628;4631.143238500879;4160.937585936221;4189.601987811168;4224.345226489899;4276.2089013532595;4390.429244198266;4341.110072435159;4347.869563913072;4359.455068116486;4376.277914144178;4388.407564978735;4175.331946888483;4185.372194170868;4185.727927684691;4192.350185721188;4201.306784380574;4210.473690788652;4228.429162063113;4243.108947121108;4244.214820258231;4258.787060646986;4194.706370554062;4202.432671589327;4230.555791641895;4259.488861336628;4295.4415925929425;4661.580457758784;4698.600618947394;4728.598069164125;4730.2431610942085;4730.763461826929;4903.926441103386;4969.254611808222;5036.269740887587;5048.5203905628905;5082.1360368737405;5114.43741188469;5122.92315615266;5126.256692779526;5126.5642420394315;5133.974330128362;4747.596454301423;4781.450133234723;4790.377250957354;4794.9280760788615;4800.087983283165;4935.235038569028;5062.924056139161;5117.309163262987;5119.283300337956;5226.92159617606;4925.926111108336;4955.82654607087;4969.602431805447;4976.477469865685;4978.4025916890205;5011;5013.373328333958;5017.046761115376;5021.949780502221;5060.987802439527;4823.107725070873;4893.4617192108835;4915.852524424273;4929.852104436873;4947.802087916443;4914.066327397783;4943.110240156806;4951.3810773653295;4956.702597844069;4988.8752781180765;4078;4168.103857670615;4168.395582640561;4175.373693945932;4211.136716973013;3619.7466177367332;3624.5106910567265;3649.1424515238896;3685.4287585424304;3702.629737026276;4311.461067366579;4316.223079845595;4321.891952701145;4324.35134729793;4353.4122893408985;5022.698638081727;5026.54761071498;5029.044481548494;5029.723365214875;5064.265681476165;4228.069824638575;4245.150969806051;4278.935815962763;4287.506936702257;4297.699161058758;4438.312061630452;4440.289553671397;4444.82220711168;4447.266201076851;4477.306017567283;4646;4713.363695901009;4713.622910167247;4716.5990890774665;4752.500987396274;4973.204287314013;5011.223260394645;5028.899422011613;5042.29407882904;5051.62112841531;4751.524847515221;4752.239641657318;4764.094821983812;4765.547273009059;4808.206645903387;4779.498152694056;4860.586850117779;4940.333055037524;4955.35580374549;5020.221865610836;4950.504949505021;5031;5037.748112594282;5053.0399224147295;5062.190049592047;4889.511048895082;4897.877553061202;4921.458639549718;4930.063287975273;4970.900581988268;4687.226607609706;4813.542713442217;4851.660383773102;4888.9755551222015;4942.135126352802;4812.181929071996;4846.973136115225;4854.490278520704;4858.198397264412;4860.756962151808;4922.384701912261;4923.113839508923;4923.753812309442;4928.260760885896;4956.306117143531;4674.69614475058;4736.668433209642;4780.904381912412;4797.880052998703;4861.708297502161 04:29:14 INFO - PROCESS | 29878 | |1;ext.html;547.6694444855607;582.3215953414273;585.5959388022264;592.6829146406668;603.3604379357882;26980.190594282154;29784.510774461272;29841.40317193655;29844;29856.55215171774;29554.11337659868;39265.21469570607;40520.797396013055;40583.59416405843;43959.78020109903;7937.087234967966;8073.273405393514;8076.838463230732;8080.676772929105;8087.433879628428;7508.62456877154;7884.566348850811;7970.960145199295;7984.401169912251;7986.720464783733;9985;10234.385936843813;10238.948805255963;10647.467626618638;10731.463426828666;10293.330933489298;10369.392744124598;10375.325603835727;10377.584896604127;10378.792424151512;7484.288992545699;9799.559019844124;10084.344517606332;10161.847572286422;10227.488625568692;39350;39956.80021599874;39962;40123.3981490275;40187.7990610048;2872.6121973533513;2906.593076969226;2919.2487653365324;2996.6703662597097;3088.3051313454293;29558.70441295581;29769.25576860574;30015.94944176965;30317.848410758026;30675.84662076675;3512.648735126467;3588.174719814457;3611.1513794258326;3631.7094632429344;3632.0738211756097;245100;245975;246079;246244;248652.9999999982;51173.232401514004;51697.7415112922;51726.22410663843;51770.22344664833;51791;54873.725631371584;55378;55382.72308638431;55461.44538554644;55597.444025560035;13075.215487070805;13206.33968301581;13244.602661920158;13284.73430531384;13309.201447913156;9785.559649815776;9829.852552211723;9860.704178874645;9870.506474676238;9889.307748457682;13777.380017899219;15382.153981530899;15814.68370632581;15826.28781704826;15861.444849430214;5573.554115670737;5722.54219662426;5739.397363276879;5752.137179423036;5830.416958304136;844.687465637718;864.4510735682886;917.3716004536781;939.9389039712397;966.1015255812163;27372;27540.173794786187;28262;28464.573031404547;28736.712632873823;936.1200471556715;951.46242373059;958.6980101268078;960.4189465373348;989.9554520046615;14598.41606335756;14744.483943061941;14777.926110369379;14964.401423943138;15151.924240378728;383616;385351;385513;387959;389194;50590.494095059315;50680;50699.49300507022;50727.239091413656;50788.49211507911;1107.7230692326918;1135.5968631135993;1153.0544953138515;1154.3362566524281;1160.8606967163826;25262;26775.86612066927;26844.865775671777;27086.864565677835;27207;42630.78684606557;43035.56964430441;43087.56912430836;43131.78434107935;43175.784121079196;330583;338077;338438;340038;340150;31029.68970310268;31513.684863151077;31839.68160318367;36852;37294;36700.81649591735;46707.53292467032;46746.53253467422;47172.528274718185;48157;36000.09999750027;36191.27617447689;36570.8171459141;36639.816800915825;37394.62605373911;35483.29033419266;36683.26633467369;36804.631953680124;36933;37311.626883730816;35875.64124358723;35950.28099438049;36468.45297320489;37368;37416.2516749658;31248.531272031392;33473.33053338871;33657.49513757246;33715.662843371254;34046.48930265998;25154.62268066017;25649.358766030997;25783;25831.42688738098;25855.741442585335 04:29:14 INFO - PROCESS | 29878 | |2;jquery.html;349.29093939303226;366.8532586965214;371.74906937816974;378.34546235013426;384.3657964358808;12487.812682809754;15529.689406211883;15944.36222551098;16992.830071699278;17076.573085672866;15605.531834044968;15618.219089045531;15659.13874736889;16101.677966440664;16122.193890305496;4594;4902.191138462158;5111.69329840209;5123.641345105825;5151.253068305094;5025.798968041274;5057.03916255912;5104.004719079781;5121.206213036967;5127;4832.71003739775;5048.268001139832;5054;5064.797408103672;5075.6447048706605;4965.652404331699;5013.799448022075;5014.097462456756;5014.423341315759;5050.191969284915;4360.454943132108;4397.670174736908;4431.711938723973;4474.507804141542;4475.932861007088;17676.381326653507;18692.626147476974;19456.124474398544;20022.79977200224;20120.698189527167;1837.145727236828;1888.5089876632176;1894.2612381171355;2061.0416156487304;2306.6655334976335;8116.1478044805035;8306.335493160532;8319.916800831974;8362.749117526484;9188.540572971324;1759.129231030648;1820.0444766497562;1836.182898610119;1841.9355322563647;1911.9426417207505;60316.698416507636;61157.38842611561;61606.38393616051;62146.37853621451;67576;8554.401191916631;9188;9491.430514169171;10011.249156313306;10024.599016039423;11806.5867694632;12579.245245285128;12580.87419125797;12828.871711282954;12965.546205882749;6518.217813862367;6547.9017814732815;6920.9307906920285;6960.65196740161;7129.465290103263;6256.905041617699;6360.777372792023;6367.522435817332;6425.1968503937005;6791.252962174157;4053.574374690674;4119.588041195916;4194.224068547329;4233.597808208216;4408.360787685768;1712.6317841664102;1727.7322015087682;1753.4651931161118;1802.9459116226535;1817.6092140189924;840.1808236968907;849.8427790858711;861.978450538729;896.3546246702364;917.6237742525533;15806.36774529029;15840.52478425626;16190.676186476205;16453.91773041127;16617.252223649964;821.8849361089452;831.5052543736467;846.593635055177;849.4945507423073;897.533282692996;8901.866472002925;8955.149260820212;8961.327900407496;9264.39781414206;9352.67265645699;380263;395268;401058;401466;406890;70965.64517177381;72830.90753638699;75926.62036689892;77008.61495692485;77014.22985770182;875.7170548356329;886.8403687336341;887.5784002598714;901.315000599536;905.4748246017426;14115.717685646436;14340.784888226894;14383.928080359532;14462.349194286493;14801.333939972512;27379.31551711223;29837;34716.652833471344;35694.464583030756;35805.46291805573;43905.56094439015;47340.7632961833;48346;49077;49711.75144124256;7278.67245973943;7485.139208991036;7524.134724506752;7600.923990760021;7710.92289077102;10812.29665095686;10868.293560918515;11305.26515776472;11426.25729327591;11615;9178.495182765013;9645.807083858143;9653.758656033655;9939.801203976025;10046;9649;9946.303758736818;10279.074883260542;10335.17318614494;10340.586376544854;9782.9021709782;9795.461249631338;9978.401295922267;10086.629642034262;10096.29325947206;7288.380487658608;7319.597422141834;7387.8153046174275;7439.55362678241;7494.70021199146;8553.64233125183;9649.372790768577;10007.899921000988;10047.246456515644;10259.230557708352 04:29:14 INFO - PROCESS | 29878 | |3;mootools.html;1212.812014137809;1375.154280117728;1421.6516953346431;1467.9926600366996;1702.3190723710507;6668.299828518008;7390.963045184773;7681.23187681233;7686.5772382518935;7915.248051435119;7678.500897441676;7818.413618978585;7836.843263134748;7838.294553490185;7838.608069596512;2189.9671504927437;2216.4015715756736;2219.323106452531;2219.966700499486;2223.1329781385193;2134.2210093315907;2156.223759446606;2190.671399290103;2194.813440857529;2196.253273886878;2154;2155.256436529403;2159.373781603333;2165.72928383952;2209.1384360099573;2164.145162660756;2166.447555873258;2169.349195241421;2172.3374370816896;2173.3262688566597;2104.947376315589;2119.9576008479744;2142.8821414822332;2145.249162793026;2147.4953385954286;7430.256974302581;7709.344705700022;7768.65041073153;7791.649375778104;7832.2559356861575;4515.525869783658;4620.237660785966;4624.630029597658;4627.606653434428;4632.62938964882;2738.4523095380805;2814.5074611942828;2883.1927060422904;2901;2906.985465072661;866.9436486628349;868.452874688942;870.1124852650262;883.9734807955772;891.5809569502323;10004.94997525015;10022.298439109265;10047.748806279827;10061.547230374577;10075.949620251926;3778.7543809652284;3864.478295430081;3892.2799282132896;3928.332183528807;3932.33150364433;7842.215778422226;7844.215578442224;7885.921140788519;7898.328642065489;7920.2871741542385;4341.544137865541;4353.238183317907;4355.934660980088;4361.454818147731;4423.73457592539;3621.3662609043313;4047.453593764863;4059.5534491205945;4098.995746042179;4268.189044081614;3615.7107431405425;3626.165981824195;3714.0343510687417;3740.831662575191;3775.093977445393;1166.7374840660952;1199.2264989081993;1223.9632811015683;1225.975480490403;1232.580922486341;823.6129019360891;834.1366685480499;843.8438888805467;851.7189327521903;854.1629203380721;5013.974930125399;5109.489051094861;5124.615653825978;5311.548518375904;5343.893122137535;865.8354912566592;880.6081293824315;917.9954100229456;931.3387494878712;956.799072194833;3380.7802492837886;3599.6580324869096;3757.5866654667966;3768.8869333920024;3797.6392242736897;9153.084691530927;9373.718788436357;9407.858882116774;9442.15020648145;9643.373180743369;8638.17937295956;8962.058983806737;9001.684941027033;9384.90615093854;9400.95299523498;6919.654017299215;7134.714611415485;7294.343509084208;7307.3058059484265;7309.049823522908;5150.793968241228;5183.92224116631;5265.473452654704;5457.890842183214;5565.193047008081;8529.872051919103;8697.739067827975;8824.558772061242;8876.556172191495;8887.422317549339;8713.52075635846;9041.23149532297;9077.818443631222;9227.953860230657;9278.257739380699;6534.509911756732;6646.468282537288;7097.219305876246;7324.377427918686;7441.404687625085;7378.483506154517;7543.622818858926;7605.505642133244;7605.6577454013595;7608.353289970193;6257.092721555258;6491.058796474579;6585.275619681927;6715.731370745116;6736.7642132525125;6499.350064993463;6543.018547217765;6573.934260657332;6588.275289718032;6656.434203092791;6148.108524263867;6205.906911396423;6227.1282020516255;6234.501239900706;6238.064290356483;4062.6952978526137;4151.1697660468635;4240.978795106004;4241.321388577812;4269.40228368035;4252.149570085933;4300.935485967776;4346.065595896897;4403.8458653946955;4413.757243351518 04:29:14 INFO - PROCESS | 29878 | |4;prototype.html;295.3959153531029;300.787944499128;302.8304149676183;308.7298613713001;313.6346156727412;4641.582257596816;4693.694909830863;4717.198076327024;4726.598239149676;4744.9323902122005;4592.173408786417;4603.355530225771;4634.467036290827;4668.603168730662;4694.17851875922;3523.4714792781033;3613.981930090359;3624.2932628137523;3700.2044560419504;3743.4010558310674;3670.8348124334334;3687.15195505034;3706.4996225509617;3709.833057512405;3714.164313029574;3509.842057107436;3670.687991520724;3676.5588129424373;3679.675316885919;3692.519972403584;3581.2658405026905;3637.836297366625;3685.649863262986;3689.760165589228;3701.81490925454;3271.4460631200227;3284.458064419368;3286.0963235110535;3286.7370610351113;3305.834708264601;4561.201789686825;4583.381243532114;4585.180114229758;4588.7476188809605;4591.586757191869;3924.725269231155;4200.474940632421;4210.347396153601;4307.763073030982;4308.9353659695125;4911.901761964777;4919.2867034279825;4932;4943.258511223309;4958.330625365602;3498.5306171407815;3514.5431093957877;3514.7715398499017;3519.6449366993893;3541.5750109986707;5007.824726134568;5119.462456442057;5140.126178549618;5152.587792976553;5159.974200129013;3546.432570788713;3577.94633080504;3589.515415418937;3599.4240921452442;3624.927501450009;3636.7272454566014;3641.089727568108;3646.5077214575695;3647.507586475845;3650.105724097613;4166.937430955087;4170.4995400552125;4187.937180942288;4192.727472714264;4194.224068547329;4586.637881490968;4852.17513022787;4864.221724524059;4932.26016097581;4959.380077490314;1261.3314943080181;1269.936503174856;1277.2911034376011;1279.3411393132544;1281.6603600045996;803.1446509467398;828.8963879515061;866.9812969760507;885.4156256870434;890.3767362846033;4382.189294980439;4392.890177745582;4406.334167828418;4413.646908247332;4420.369879921442;5030.949690503048;5031.572316353077;5074.086664400444;5121.61587880903;5149.201873709506;4225.788710564521;4317.222899878052;4335.1329734053315;4357.477102747627;4377.868663940087;2650.4831557846037;2658.3752818087733;2664.200739778059;2694.461107778452;2699.25770413138;4732.668713190113;4760.928586071212;4761.619070474354;4763.476017638057;4764.475907650156;4938.629602779806;5123.3339665843205;5136.614753893398;5139.357580302462;5149.691018538825;4756.999999999931;4760.214564596803;4785.186518291899;4788.401449818773;4792.329073929653;5028.899422011613;5029.7736601852275;5034.421041580265;5041;5071.898562028812;4799.208130658402;4848.248521479177;4932.8766780830765;4948.257761335684;4955.339961112923;4710.458297295855;4711.363965864564;4737.957649317144;4761.381130415675;4766.7854946526795;3545.2377738786286;3548.6983606392714;3558.91102722434;3566;3581.641835816502;4837.186055229289;4965.205567109245;5007.84976450707;5108.61685373591;5122.616893438825;4925.655204135676;4955.876103097451;4977.954629527695;5017.37282839645;5027.723475208899;4972.950270497248;4988.927380613186;5035.723035232951;5039;5052.267421223828;4793.424789025339;4950.257461380822;4977.8257760978195;4978.626603004862;4992.751812046989;4813.927791083067;4890.217565189552;5018.899622007466;5037.19404895215;5039.874003149951;4890.951090489049;4929.334539837076;4945.530174633404;4947.901041979068;4964.67729597575 04:29:14 INFO - PROCESS | 29878 | |5;yui.html;594.4115325827432;643.1285608001156;674.8549061951679;688.8140202145421;745.4595418321718;13160.802587961176;14151.716965660693;14159.716805663906;14166.362513686883;14195.787063194035;12867.613971580868;13058.216507009585;13604.863951360509;13612.387442565083;13612.795808062887;633.3571425003602;787.6022608582658;806.5322113174374;806.6289506826866;810.3193317613204;771.5949126708495;801.9919800801975;809.3808236698901;810.0198759501011;810.5582457560644;843.0515669871395;844.6410275632832;846.2299307630058;846.5047946951041;846.9280111190557;641.0768493369579;809.401043228007;841.5203334099566;841.9747407577782;842.3724325377572;597.6712807955607;597.8176656119882;597.8385835824303;598.2193237824637;611.5474548833876;11843.940780296129;11856.822147667792;11877.346745928971;11896.762064758655;11919.165658403916;12846.421911014028;13443.798343024862;13492;13494.122882012736;13681.863181368159;11630.6510804676;11745.412729363497;11749.177557570973;11851.229670071503;12049.93975030128;4180.665546756252;4182.456280683522;4225.154969006211;4234.5765423457715;4239.639630631369;63729.68135159341;66369;67989.66005170041;68586;68667.65666171638;10597.311174773615;10701.197410194267;10816.621418250323;10888.891111088946;10909.509072091774;20459.07934142967;20464.59070818575;20468.283610073577;20486.05382488661;20493.18027278922;6838.111045564045;6959.965200173967;7007.92992070083;7024.2273349931465;7133.144022717307;3701.4447832824753;3709.0541911812315;3726.347889119393;3755.9248815023548;3791.563970143469;2818.3376906426975;2944.1167649705003;2948.8820447181874;2954.1137658702733;2959.467295886718;497.2019908047604;571.7370009795458;598.3507893935042;609.1836938502428;615.689077016104;452.181551391975;547.8356493052069;565.6464709556527;585.2713371852071;589.7228302697725;7666.731664391719;7907.428144830161;7992;8647.221750042401;8742.30061595071;373.0598890795181;373.534948988511;438.2965340628309;448.13062658442516;468.85131428001534;4039.555648878621;4297.247981603207;4408.779561021936;4439;4546.18041000458;99918;101840;102155.48922255341;103246.48376758219;104476.47761761295;89209;91404;91745;91858.54070729604;94420.52789736145;81206.18793812105;81272;81627;82572.76140857892;84157;8657.307415406627;8730.912690873263;8818.470891746516;8993.865092023756;9053.230475409664;44043.55956440394;44734;45209.09581808411;45361;45874;63499.36500634935;65503.67248163729;65556;65896.67051664711;66320;11174.27367221128;11267.7746445069;11435.714107147387;11592.188546801643;11676.241044332091;12847.807282890952;12899.484020639069;13072.411741471848;13189;13601.183928964294;12354.938225308815;12539.498420063095;12578.748425031632;12860;12976.15654982423;12488.625341239414;12491.437885295365;12510.562130325396;12714.364281785689;12799.513803788052;11834.940825295818;11898.464569094585;11903.82144267854;11949.522019119486;11981.880181198076;9718.805623887623;9755.170810480904;9981.351212171186;10018.298719089886;10018.549165287433;9593.42439453635;10118.711418788776;10135.54390052464;10228.07947284748;10232.59069637236 04:29:14 INFO - PROCESS | 29878 | __end_tp_report 04:29:14 INFO - PROCESS | 29878 | __start_cc_report 04:29:14 INFO - PROCESS | 29878 | _x_x_mozilla_cycle_collect,959 04:29:14 INFO - PROCESS | 29878 | __end_cc_report 04:29:14 INFO - PROCESS | 29878 | __startTimestamp1466508554370__endTimestamp 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | ------- Summary: start ------- 04:29:14 INFO - PROCESS | 29878 | Number of tests: 6 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | [#0] dojo.html Cycles:165 Average:4613.93 Median:4765.55 stddev:497.46 (10.4%) stddev-sans-first:473.15 04:29:14 INFO - PROCESS | 29878 | Values: 2597.0 2618.9 2647.7 2657.1 2664.4 4469.6 4547.7 4585.2 4660.5 4706.3 4528.7 4535.5 4553.9 4566.5 4631.1 4160.9 4189.6 4224.3 4276.2 4390.4 4341.1 4347.9 4359.5 4376.3 4388.4 4175.3 4185.4 4185.7 4192.4 4201.3 4210.5 4228.4 4243.1 4244.2 4258.8 4194.7 4202.4 4230.6 4259.5 4295.4 4661.6 4698.6 4728.6 4730.2 4730.8 4903.9 4969.3 5036.3 5048.5 5082.1 5114.4 5122.9 5126.3 5126.6 5134.0 4747.6 4781.5 4790.4 4794.9 4800.1 4935.2 5062.9 5117.3 5119.3 5226.9 4925.9 4955.8 4969.6 4976.5 4978.4 5011.0 5013.4 5017.0 5021.9 5061.0 4823.1 4893.5 4915.9 4929.9 4947.8 4914.1 4943.1 4951.4 4956.7 4988.9 4078.0 4168.1 4168.4 4175.4 4211.1 3619.7 3624.5 3649.1 3685.4 3702.6 4311.5 4316.2 4321.9 4324.4 4353.4 5022.7 5026.5 5029.0 5029.7 5064.3 4228.1 4245.2 4278.9 4287.5 4297.7 4438.3 4440.3 4444.8 4447.3 4477.3 4646.0 4713.4 4713.6 4716.6 4752.5 4973.2 5011.2 5028.9 5042.3 5051.6 4751.5 4752.2 4764.1 4765.5 4808.2 4779.5 4860.6 4940.3 4955.4 5020.2 4950.5 5031.0 5037.7 5053.0 5062.2 4889.5 4897.9 4921.5 4930.1 4970.9 4687.2 4813.5 4851.7 4889.0 4942.1 4812.2 4847.0 4854.5 4858.2 4860.8 4922.4 4923.1 4923.8 4928.3 4956.3 4674.7 4736.7 4780.9 4797.9 4861.7 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | [#1] ext.html Cycles:180 Average:47956.19 Median:20050.26 stddev:86489.24 (431.4%) stddev-sans-first:86658.62 04:29:14 INFO - PROCESS | 29878 | Values: 547.7 582.3 585.6 592.7 603.4 26980.2 29784.5 29841.4 29844.0 29856.6 29554.1 39265.2 40520.8 40583.6 43959.8 7937.1 8073.3 8076.8 8080.7 8087.4 7508.6 7884.6 7971.0 7984.4 7986.7 9985.0 10234.4 10238.9 10647.5 10731.5 10293.3 10369.4 10375.3 10377.6 10378.8 7484.3 9799.6 10084.3 10161.8 10227.5 39350.0 39956.8 39962.0 40123.4 40187.8 2872.6 2906.6 2919.2 2996.7 3088.3 29558.7 29769.3 30015.9 30317.8 30675.8 3512.6 3588.2 3611.2 3631.7 3632.1 245100.0 245975.0 246079.0 246244.0 248653.0 51173.2 51697.7 51726.2 51770.2 51791.0 54873.7 55378.0 55382.7 55461.4 55597.4 13075.2 13206.3 13244.6 13284.7 13309.2 9785.6 9829.9 9860.7 9870.5 9889.3 13777.4 15382.2 15814.7 15826.3 15861.4 5573.6 5722.5 5739.4 5752.1 5830.4 844.7 864.5 917.4 939.9 966.1 27372.0 27540.2 28262.0 28464.6 28736.7 936.1 951.5 958.7 960.4 990.0 14598.4 14744.5 14777.9 14964.4 15151.9 383616.0 385351.0 385513.0 387959.0 389194.0 50590.5 50680.0 50699.5 50727.2 50788.5 1107.7 1135.6 1153.1 1154.3 1160.9 25262.0 26775.9 26844.9 27086.9 27207.0 42630.8 43035.6 43087.6 43131.8 43175.8 330583.0 338077.0 338438.0 340038.0 340150.0 31029.7 31513.7 31839.7 36852.0 37294.0 36700.8 46707.5 46746.5 47172.5 48157.0 36000.1 36191.3 36570.8 36639.8 37394.6 35483.3 36683.3 36804.6 36933.0 37311.6 35875.6 35950.3 36468.5 37368.0 37416.3 31248.5 33473.3 33657.5 33715.7 34046.5 25154.6 25649.4 25783.0 25831.4 25855.7 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | [#2] jquery.html Cycles:180 Average:23654.37 Median:26624.10 stddev:65377.16 (245.6%) stddev-sans-first:65537.14 04:29:14 INFO - PROCESS | 29878 | Values: 349.3 366.9 371.7 378.3 384.4 12487.8 15529.7 15944.4 16992.8 17076.6 15605.5 15618.2 15659.1 16101.7 16122.2 4594.0 4902.2 5111.7 5123.6 5151.3 5025.8 5057.0 5104.0 5121.2 5127.0 4832.7 5048.3 5054.0 5064.8 5075.6 4965.7 5013.8 5014.1 5014.4 5050.2 4360.5 4397.7 4431.7 4474.5 4475.9 17676.4 18692.6 19456.1 20022.8 20120.7 1837.1 1888.5 1894.3 2061.0 2306.7 8116.1 8306.3 8319.9 8362.7 9188.5 1759.1 1820.0 1836.2 1841.9 1911.9 60316.7 61157.4 61606.4 62146.4 67576.0 8554.4 9188.0 9491.4 10011.2 10024.6 11806.6 12579.2 12580.9 12828.9 12965.5 6518.2 6547.9 6920.9 6960.7 7129.5 6256.9 6360.8 6367.5 6425.2 6791.3 4053.6 4119.6 4194.2 4233.6 4408.4 1712.6 1727.7 1753.5 1802.9 1817.6 840.2 849.8 862.0 896.4 917.6 15806.4 15840.5 16190.7 16453.9 16617.3 821.9 831.5 846.6 849.5 897.5 8901.9 8955.1 8961.3 9264.4 9352.7 380263.0 395268.0 401058.0 401466.0 406890.0 70965.6 72830.9 75926.6 77008.6 77014.2 875.7 886.8 887.6 901.3 905.5 14115.7 14340.8 14383.9 14462.3 14801.3 27379.3 29837.0 34716.7 35694.5 35805.5 43905.6 47340.8 48346.0 49077.0 49711.8 7278.7 7485.1 7524.1 7600.9 7710.9 10812.3 10868.3 11305.3 11426.3 11615.0 9178.5 9645.8 9653.8 9939.8 10046.0 9649.0 9946.3 10279.1 10335.2 10340.6 9782.9 9795.5 9978.4 10086.6 10096.3 7288.4 7319.6 7387.8 7439.6 7494.7 8553.6 9649.4 10007.9 10047.2 10259.2 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | [#3] mootools.html Cycles:180 Average:5015.23 Median:5288.51 stddev:2768.32 (52.3%) stddev-sans-first:2761.33 04:29:14 INFO - PROCESS | 29878 | Values: 1212.8 1375.2 1421.7 1468.0 1702.3 6668.3 7391.0 7681.2 7686.6 7915.2 7678.5 7818.4 7836.8 7838.3 7838.6 2190.0 2216.4 2219.3 2220.0 2223.1 2134.2 2156.2 2190.7 2194.8 2196.3 2154.0 2155.3 2159.4 2165.7 2209.1 2164.1 2166.4 2169.3 2172.3 2173.3 2104.9 2120.0 2142.9 2145.2 2147.5 7430.3 7709.3 7768.7 7791.6 7832.3 4515.5 4620.2 4624.6 4627.6 4632.6 2738.5 2814.5 2883.2 2901.0 2907.0 866.9 868.5 870.1 884.0 891.6 10004.9 10022.3 10047.7 10061.5 10075.9 3778.8 3864.5 3892.3 3928.3 3932.3 7842.2 7844.2 7885.9 7898.3 7920.3 4341.5 4353.2 4355.9 4361.5 4423.7 3621.4 4047.5 4059.6 4099.0 4268.2 3615.7 3626.2 3714.0 3740.8 3775.1 1166.7 1199.2 1224.0 1226.0 1232.6 823.6 834.1 843.8 851.7 854.2 5014.0 5109.5 5124.6 5311.5 5343.9 865.8 880.6 918.0 931.3 956.8 3380.8 3599.7 3757.6 3768.9 3797.6 9153.1 9373.7 9407.9 9442.2 9643.4 8638.2 8962.1 9001.7 9384.9 9401.0 6919.7 7134.7 7294.3 7307.3 7309.0 5150.8 5183.9 5265.5 5457.9 5565.2 8529.9 8697.7 8824.6 8876.6 8887.4 8713.5 9041.2 9077.8 9228.0 9278.3 6534.5 6646.5 7097.2 7324.4 7441.4 7378.5 7543.6 7605.5 7605.7 7608.4 6257.1 6491.1 6585.3 6715.7 6736.8 6499.4 6543.0 6573.9 6588.3 6656.4 6148.1 6205.9 6227.1 6234.5 6238.1 4062.7 4151.2 4241.0 4241.3 4269.4 4252.1 4300.9 4346.1 4403.8 4413.8 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | [#4] prototype.html Cycles:180 Average:4096.90 Median:4681.15 stddev:1185.03 (25.3%) stddev-sans-first:1153.49 04:29:14 INFO - PROCESS | 29878 | Values: 295.4 300.8 302.8 308.7 313.6 4641.6 4693.7 4717.2 4726.6 4744.9 4592.2 4603.4 4634.5 4668.6 4694.2 3523.5 3614.0 3624.3 3700.2 3743.4 3670.8 3687.2 3706.5 3709.8 3714.2 3509.8 3670.7 3676.6 3679.7 3692.5 3581.3 3637.8 3685.6 3689.8 3701.8 3271.4 3284.5 3286.1 3286.7 3305.8 4561.2 4583.4 4585.2 4588.7 4591.6 3924.7 4200.5 4210.3 4307.8 4308.9 4911.9 4919.3 4932.0 4943.3 4958.3 3498.5 3514.5 3514.8 3519.6 3541.6 5007.8 5119.5 5140.1 5152.6 5160.0 3546.4 3577.9 3589.5 3599.4 3624.9 3636.7 3641.1 3646.5 3647.5 3650.1 4166.9 4170.5 4187.9 4192.7 4194.2 4586.6 4852.2 4864.2 4932.3 4959.4 1261.3 1269.9 1277.3 1279.3 1281.7 803.1 828.9 867.0 885.4 890.4 4382.2 4392.9 4406.3 4413.6 4420.4 5030.9 5031.6 5074.1 5121.6 5149.2 4225.8 4317.2 4335.1 4357.5 4377.9 2650.5 2658.4 2664.2 2694.5 2699.3 4732.7 4760.9 4761.6 4763.5 4764.5 4938.6 5123.3 5136.6 5139.4 5149.7 4757.0 4760.2 4785.2 4788.4 4792.3 5028.9 5029.8 5034.4 5041.0 5071.9 4799.2 4848.2 4932.9 4948.3 4955.3 4710.5 4711.4 4738.0 4761.4 4766.8 3545.2 3548.7 3558.9 3566.0 3581.6 4837.2 4965.2 5007.8 5108.6 5122.6 4925.7 4955.9 4978.0 5017.4 5027.7 4973.0 4988.9 5035.7 5039.0 5052.3 4793.4 4950.3 4977.8 4978.6 4992.8 4813.9 4890.2 5018.9 5037.2 5039.9 4891.0 4929.3 4945.5 4947.9 4964.7 04:29:14 INFO - PROCESS | 29878 | 04:29:14 INFO - PROCESS | 29878 | [#5] yui.html Cycles:180 Average:18786.40 Median:24226.17 stddev:27119.93 (111.9%) stddev-sans-first:27161.60 04:29:14 INFO - PROCESS | 29878 | Values: 594.4 643.1 674.9 688.8 745.5 13160.8 14151.7 14159.7 14166.4 14195.8 12867.6 13058.2 13604.9 13612.4 13612.8 633.4 787.6 806.5 806.6 810.3 771.6 802.0 809.4 810.0 810.6 843.1 844.6 846.2 846.5 846.9 641.1 809.4 841.5 842.0 842.4 597.7 597.8 597.8 598.2 611.5 11843.9 11856.8 11877.3 11896.8 11919.2 12846.4 13443.8 13492.0 13494.1 13681.9 11630.7 11745.4 11749.2 11851.2 12049.9 4180.7 4182.5 4225.2 4234.6 4239.6 63729.7 66369.0 67989.7 68586.0 68667.7 10597.3 10701.2 10816.6 10888.9 10909.5 20459.1 20464.6 20468.3 20486.1 20493.2 6838.1 6960.0 7007.9 7024.2 7133.1 3701.4 3709.1 3726.3 3755.9 3791.6 2818.3 2944.1 2948.9 2954.1 2959.5 497.2 571.7 598.4 609.2 615.7 452.2 547.8 565.6 585.3 589.7 7666.7 7907.4 7992.0 8647.2 8742.3 373.1 373.5 438.3 448.1 468.9 4039.6 4297.2 4408.8 4439.0 4546.2 99918.0 101840.0 102155.5 103246.5 104476.5 89209.0 91404.0 91745.0 91858.5 94420.5 81206.2 81272.0 81627.0 82572.8 84157.0 8657.3 8730.9 8818.5 8993.9 9053.2 44043.6 44734.0 45209.1 45361.0 45874.0 63499.4 65503.7 65556.0 65896.7 66320.0 11174.3 11267.8 11435.7 11592.2 11676.2 12847.8 12899.5 13072.4 13189.0 13601.2 12354.9 12539.5 12578.7 12860.0 12976.2 12488.6 12491.4 12510.6 12714.4 12799.5 11834.9 11898.5 11903.8 11949.5 11981.9 9718.8 9755.2 9981.4 10018.3 10018.5 9593.4 10118.7 10135.5 10228.1 10232.6 04:29:14 INFO - PROCESS | 29878 | -------- Summary: end -------- 04:29:14 INFO - PROCESS | 29878 | 04:29:15 INFO - TEST-INFO | 29878: exit 0 04:29:15 INFO - TEST-OK | dromaeo_css | took 1122778ms 04:29:15 INFO - TEST-START | kraken 04:29:15 INFO - operating with platform_type : linux_ 04:29:15 INFO - Initialising browser for kraken test... 04:29:15 INFO - TEST-INFO | started process 30060 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLN1RJl/profile http://localhost:56039/getInfo.html) 04:29:20 INFO - PROCESS | 30060 | __metrics Screen width/height:1600/1200 04:29:20 INFO - PROCESS | 30060 | colorDepth:24 04:29:20 INFO - PROCESS | 30060 | Browser inner width/height: 1024/697 04:29:20 INFO - PROCESS | 30060 | __metrics 04:29:22 INFO - PROCESS | 30060 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:29:22 INFO - PROCESS | 30060 | console.error: PushService: 04:29:22 INFO - PROCESS | 30060 | stateChangeProcessEnqueue: Error transitioning state 04:29:22 INFO - PROCESS | 30060 | UnknownError 04:29:23 INFO - TEST-INFO | 30060: exit 0 04:29:23 INFO - Browser initialized. 04:29:23 INFO - Running cycle 1/1 for kraken test... 04:29:23 INFO - TEST-INFO | started process 30142 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLN1RJl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:35 INFO - PROCESS | 30142 | RSS: Main: 175403008 04:29:35 INFO - PROCESS | 30142 | 04:29:38 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56039/tests/kraken/driver.html?testName=audio-beat-detection) 04:29:39 INFO - PROCESS | 30142 | RSS: Main: 233549824 04:29:39 INFO - PROCESS | 30142 | 04:29:42 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56039/tests/kraken/driver.html?testName=audio-dft) 04:29:42 INFO - PROCESS | 30142 | RSS: Main: 221736960 04:29:42 INFO - PROCESS | 30142 | 04:29:45 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56039/tests/kraken/driver.html?testName=audio-fft) 04:29:46 INFO - PROCESS | 30142 | RSS: Main: 288784384 04:29:46 INFO - PROCESS | 30142 | 04:29:48 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56039/tests/kraken/driver.html?testName=audio-oscillator) 04:29:49 INFO - PROCESS | 30142 | RSS: Main: 198791168 04:29:49 INFO - PROCESS | 30142 | 04:29:51 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56039/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:29:52 INFO - PROCESS | 30142 | RSS: Main: 202452992 04:29:52 INFO - PROCESS | 30142 | 04:29:55 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56039/tests/kraken/driver.html?testName=imaging-darkroom) 04:29:55 INFO - PROCESS | 30142 | RSS: Main: 238596096 04:29:55 INFO - PROCESS | 30142 | 04:29:59 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56039/tests/kraken/driver.html?testName=imaging-desaturate) 04:30:00 INFO - PROCESS | 30142 | RSS: Main: 234934272 04:30:00 INFO - PROCESS | 30142 | 04:30:03 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56039/tests/kraken/driver.html?testName=json-parse-financial) 04:30:03 INFO - PROCESS | 30142 | RSS: Main: 237441024 04:30:03 INFO - PROCESS | 30142 | 04:30:05 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56039/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:30:05 INFO - PROCESS | 30142 | RSS: Main: 225222656 04:30:05 INFO - PROCESS | 30142 | 04:30:07 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:30:08 INFO - PROCESS | 30142 | RSS: Main: 230367232 04:30:08 INFO - PROCESS | 30142 | 04:30:10 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:30:11 INFO - PROCESS | 30142 | RSS: Main: 237371392 04:30:11 INFO - PROCESS | 30142 | 04:30:14 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:30:14 INFO - PROCESS | 30142 | RSS: Main: 240525312 04:30:14 INFO - PROCESS | 30142 | 04:30:17 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:30:18 INFO - PROCESS | 30142 | RSS: Main: 240984064 04:30:18 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | Cycle 1(1): loaded http://localhost:56039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56039/tests/kraken/driver.html?testName=ai-astar) 04:30:20 INFO - PROCESS | 30142 | RSS: Main: 284332032 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | __start_tp_report 04:30:20 INFO - PROCESS | 30142 | _x_x_mozilla_page_load 04:30:20 INFO - PROCESS | 30142 | _x_x_mozilla_page_load_details 04:30:20 INFO - PROCESS | 30142 | |i|pagename|runs| 04:30:20 INFO - PROCESS | 30142 | |0;ai-astar;114;111;113;114;113;113;113;113;112;114 04:30:20 INFO - PROCESS | 30142 | |1;audio-beat-detection;161;130;137;181;130;132;152;130;166;130 04:30:20 INFO - PROCESS | 30142 | |2;audio-dft;162;166;162;160;161;159;162;161;160;163 04:30:20 INFO - PROCESS | 30142 | |3;audio-fft;76;75;80;104;76;77;76;75;104;80 04:30:20 INFO - PROCESS | 30142 | |4;audio-oscillator;86;89;114;86;92;88;113;86;87;119 04:30:20 INFO - PROCESS | 30142 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 04:30:20 INFO - PROCESS | 30142 | |6;imaging-darkroom;146;146;146;148;148;146;146;146;147;147 04:30:20 INFO - PROCESS | 30142 | |7;imaging-desaturate;95;95;94;95;95;94;95;95;94;94 04:30:20 INFO - PROCESS | 30142 | |8;json-parse-financial;62;62;63;64;73;62;62;63;62;62 04:30:20 INFO - PROCESS | 30142 | |9;json-stringify-tinderbox;66;65;65;65;65;65;67;66;66;66 04:30:20 INFO - PROCESS | 30142 | |10;stanford-crypto-aes;83;84;83;90;84;84;87;84;118;90 04:30:20 INFO - PROCESS | 30142 | |11;stanford-crypto-ccm;134;137;133;131;139;130;129;132;161;136 04:30:20 INFO - PROCESS | 30142 | |12;stanford-crypto-pbkdf2;172;173;172;170;169;168;169;168;167;167 04:30:20 INFO - PROCESS | 30142 | |13;stanford-crypto-sha256-iterative;70;69;67;65;66;66;67;67;66;65 04:30:20 INFO - PROCESS | 30142 | __end_tp_report 04:30:20 INFO - PROCESS | 30142 | __start_cc_report 04:30:20 INFO - PROCESS | 30142 | _x_x_mozilla_cycle_collect,1894 04:30:20 INFO - PROCESS | 30142 | __end_cc_report 04:30:20 INFO - PROCESS | 30142 | __startTimestamp1466508620264__endTimestamp 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | ------- Summary: start ------- 04:30:20 INFO - PROCESS | 30142 | Number of tests: 14 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 04:30:20 INFO - PROCESS | 30142 | Values: 114.0 111.0 113.0 114.0 113.0 113.0 113.0 113.0 112.0 114.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:144.50 stddev:18.78 (13.0%) stddev-sans-first:19.00 04:30:20 INFO - PROCESS | 30142 | Values: 161.0 130.0 137.0 181.0 130.0 132.0 152.0 130.0 166.0 130.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.96 (1.2%) stddev-sans-first:2.07 04:30:20 INFO - PROCESS | 30142 | Values: 162.0 166.0 162.0 160.0 161.0 159.0 162.0 161.0 160.0 163.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#3] audio-fft Cycles:10 Average:82.30 Median:76.00 stddev:11.58 (15.2%) stddev-sans-first:12.05 04:30:20 INFO - PROCESS | 30142 | Values: 76.0 75.0 80.0 104.0 76.0 77.0 76.0 75.0 104.0 80.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:86.50 stddev:13.55 (15.7%) stddev-sans-first:13.88 04:30:20 INFO - PROCESS | 30142 | Values: 86.0 89.0 114.0 86.0 92.0 88.0 113.0 86.0 87.0 119.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 04:30:20 INFO - PROCESS | 30142 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 04:30:20 INFO - PROCESS | 30142 | Values: 146.0 146.0 146.0 148.0 148.0 146.0 146.0 146.0 147.0 147.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:30:20 INFO - PROCESS | 30142 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 94.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:62.50 stddev:3.41 (5.5%) stddev-sans-first:3.57 04:30:20 INFO - PROCESS | 30142 | Values: 62.0 62.0 63.0 64.0 73.0 62.0 62.0 63.0 62.0 62.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 04:30:20 INFO - PROCESS | 30142 | Values: 66.0 65.0 65.0 65.0 65.0 65.0 67.0 66.0 66.0 66.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:84.00 stddev:10.64 (12.7%) stddev-sans-first:11.08 04:30:20 INFO - PROCESS | 30142 | Values: 83.0 84.0 83.0 90.0 84.0 84.0 87.0 84.0 118.0 90.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:135.00 stddev:9.27 (6.9%) stddev-sans-first:9.80 04:30:20 INFO - PROCESS | 30142 | Values: 134.0 137.0 133.0 131.0 139.0 130.0 129.0 132.0 161.0 136.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:169.50 stddev:2.17 (1.3%) stddev-sans-first:2.11 04:30:20 INFO - PROCESS | 30142 | Values: 172.0 173.0 172.0 170.0 169.0 168.0 169.0 168.0 167.0 167.0 04:30:20 INFO - PROCESS | 30142 | 04:30:20 INFO - PROCESS | 30142 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.62 (2.4%) stddev-sans-first:1.24 04:30:20 INFO - PROCESS | 30142 | Values: 70.0 69.0 67.0 65.0 66.0 66.0 67.0 67.0 66.0 65.0 04:30:20 INFO - PROCESS | 30142 | -------- Summary: end -------- 04:30:20 INFO - PROCESS | 30142 | 04:30:21 INFO - TEST-INFO | 30142: exit 0 04:30:21 INFO - TEST-OK | kraken | took 66274ms 04:30:21 INFO - SUITE-END | took 1189s 04:30:21 INFO - Completed test suite (00:19:49) 04:30:21 INFO - javascript benchmark 04:30:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2596.974195192899, 2618.869056547172, 2647.669041369829, 2657.14971209213, 2664.4404675018245, 4469.642428605712, 4547.727136371812, 4585.151746926821, 4660.487346391898, 4706.270528068147, 4528.7282763034245, 4535.455745310559, 4553.88615284618, 4566.497685254628, 4631.143238500879, 4160.937585936221, 4189.601987811168, 4224.345226489899, 4276.2089013532595, 4390.429244198266, 4341.110072435159, 4347.869563913072, 4359.455068116486, 4376.277914144178, 4388.407564978735, 4175.331946888483, 4185.372194170868, 4185.727927684691, 4192.350185721188, 4201.306784380574, 4210.473690788652, 4228.429162063113, 4243.108947121108, 4244.214820258231, 4258.787060646986, 4194.706370554062, 4202.432671589327, 4230.555791641895, 4259.488861336628, 4295.4415925929425, 4661.580457758784, 4698.600618947394, 4728.598069164125, 4730.2431610942085, 4730.763461826929, 4903.926441103386, 4969.254611808222, 5036.269740887587, 5048.5203905628905, 5082.1360368737405, 5114.43741188469, 5122.92315615266, 5126.256692779526, 5126.5642420394315, 5133.974330128362, 4747.596454301423, 4781.450133234723, 4790.377250957354, 4794.9280760788615, 4800.087983283165, 4935.235038569028, 5062.924056139161, 5117.309163262987, 5119.283300337956, 5226.92159617606, 4925.926111108336, 4955.82654607087, 4969.602431805447, 4976.477469865685, 4978.4025916890205, 5011.0, 5013.373328333958, 5017.046761115376, 5021.949780502221, 5060.987802439527, 4823.107725070873, 4893.4617192108835, 4915.852524424273, 4929.852104436873, 4947.802087916443, 4914.066327397783, 4943.110240156806, 4951.3810773653295, 4956.702597844069, 4988.8752781180765, 4078.0, 4168.103857670615, 4168.395582640561, 4175.373693945932, 4211.136716973013, 3619.7466177367332, 3624.5106910567265, 3649.1424515238896, 3685.4287585424304, 3702.629737026276, 4311.461067366579, 4316.223079845595, 4321.891952701145, 4324.35134729793, 4353.4122893408985, 5022.698638081727, 5026.54761071498, 5029.044481548494, 5029.723365214875, 5064.265681476165, 4228.069824638575, 4245.150969806051, 4278.935815962763, 4287.506936702257, 4297.699161058758, 4438.312061630452, 4440.289553671397, 4444.82220711168, 4447.266201076851, 4477.306017567283, 4646.0, 4713.363695901009, 4713.622910167247, 4716.5990890774665, 4752.500987396274, 4973.204287314013, 5011.223260394645, 5028.899422011613, 5042.29407882904, 5051.62112841531, 4751.524847515221, 4752.239641657318, 4764.094821983812, 4765.547273009059, 4808.206645903387, 4779.498152694056, 4860.586850117779, 4940.333055037524, 4955.35580374549, 5020.221865610836, 4950.504949505021, 5031.0, 5037.748112594282, 5053.0399224147295, 5062.190049592047, 4889.511048895082, 4897.877553061202, 4921.458639549718, 4930.063287975273, 4970.900581988268, 4687.226607609706, 4813.542713442217, 4851.660383773102, 4888.9755551222015, 4942.135126352802, 4812.181929071996, 4846.973136115225, 4854.490278520704, 4858.198397264412, 4860.756962151808, 4922.384701912261, 4923.113839508923, 4923.753812309442, 4928.260760885896, 4956.306117143531, 4674.69614475058, 4736.668433209642, 4780.904381912412, 4797.880052998703, 4861.708297502161], "value": 4581.781752578225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.6694444855607, 582.3215953414273, 585.5959388022264, 592.6829146406668, 603.3604379357882, 26980.190594282154, 29784.510774461272, 29841.40317193655, 29844.0, 29856.55215171774, 29554.11337659868, 39265.21469570607, 40520.797396013055, 40583.59416405843, 43959.78020109903, 7937.087234967966, 8073.273405393514, 8076.838463230732, 8080.676772929105, 8087.433879628428, 7508.62456877154, 7884.566348850811, 7970.960145199295, 7984.401169912251, 7986.720464783733, 9985.0, 10234.385936843813, 10238.948805255963, 10647.467626618638, 10731.463426828666, 10293.330933489298, 10369.392744124598, 10375.325603835727, 10377.584896604127, 10378.792424151512, 7484.288992545699, 9799.559019844124, 10084.344517606332, 10161.847572286422, 10227.488625568692, 39350.0, 39956.80021599874, 39962.0, 40123.3981490275, 40187.7990610048, 2872.6121973533513, 2906.593076969226, 2919.2487653365324, 2996.6703662597097, 3088.3051313454293, 29558.70441295581, 29769.25576860574, 30015.94944176965, 30317.848410758026, 30675.84662076675, 3512.648735126467, 3588.174719814457, 3611.1513794258326, 3631.7094632429344, 3632.0738211756097, 245100.0, 245975.0, 246079.0, 246244.0, 248652.9999999982, 51173.232401514004, 51697.7415112922, 51726.22410663843, 51770.22344664833, 51791.0, 54873.725631371584, 55378.0, 55382.72308638431, 55461.44538554644, 55597.444025560035, 13075.215487070805, 13206.33968301581, 13244.602661920158, 13284.73430531384, 13309.201447913156, 9785.559649815776, 9829.852552211723, 9860.704178874645, 9870.506474676238, 9889.307748457682, 13777.380017899219, 15382.153981530899, 15814.68370632581, 15826.28781704826, 15861.444849430214, 5573.554115670737, 5722.54219662426, 5739.397363276879, 5752.137179423036, 5830.416958304136, 844.687465637718, 864.4510735682886, 917.3716004536781, 939.9389039712397, 966.1015255812163, 27372.0, 27540.173794786187, 28262.0, 28464.573031404547, 28736.712632873823, 936.1200471556715, 951.46242373059, 958.6980101268078, 960.4189465373348, 989.9554520046615, 14598.41606335756, 14744.483943061941, 14777.926110369379, 14964.401423943138, 15151.924240378728, 383616.0, 385351.0, 385513.0, 387959.0, 389194.0, 50590.494095059315, 50680.0, 50699.49300507022, 50727.239091413656, 50788.49211507911, 1107.7230692326918, 1135.5968631135993, 1153.0544953138515, 1154.3362566524281, 1160.8606967163826, 25262.0, 26775.86612066927, 26844.865775671777, 27086.864565677835, 27207.0, 42630.78684606557, 43035.56964430441, 43087.56912430836, 43131.78434107935, 43175.784121079196, 330583.0, 338077.0, 338438.0, 340038.0, 340150.0, 31029.68970310268, 31513.684863151077, 31839.68160318367, 36852.0, 37294.0, 36700.81649591735, 46707.53292467032, 46746.53253467422, 47172.528274718185, 48157.0, 36000.09999750027, 36191.27617447689, 36570.8171459141, 36639.816800915825, 37394.62605373911, 35483.29033419266, 36683.26633467369, 36804.631953680124, 36933.0, 37311.626883730816, 35875.64124358723, 35950.28099438049, 36468.45297320489, 37368.0, 37416.2516749658, 31248.531272031392, 33473.33053338871, 33657.49513757246, 33715.662843371254, 34046.48930265998, 25154.62268066017, 25649.358766030997, 25783.0, 25831.42688738098, 25855.741442585335], "value": 17721.111592611604, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.29093939303226, 366.8532586965214, 371.74906937816974, 378.34546235013426, 384.3657964358808, 12487.812682809754, 15529.689406211883, 15944.36222551098, 16992.830071699278, 17076.573085672866, 15605.531834044968, 15618.219089045531, 15659.13874736889, 16101.677966440664, 16122.193890305496, 4594.0, 4902.191138462158, 5111.69329840209, 5123.641345105825, 5151.253068305094, 5025.798968041274, 5057.03916255912, 5104.004719079781, 5121.206213036967, 5127.0, 4832.71003739775, 5048.268001139832, 5054.0, 5064.797408103672, 5075.6447048706605, 4965.652404331699, 5013.799448022075, 5014.097462456756, 5014.423341315759, 5050.191969284915, 4360.454943132108, 4397.670174736908, 4431.711938723973, 4474.507804141542, 4475.932861007088, 17676.381326653507, 18692.626147476974, 19456.124474398544, 20022.79977200224, 20120.698189527167, 1837.145727236828, 1888.5089876632176, 1894.2612381171355, 2061.0416156487304, 2306.6655334976335, 8116.1478044805035, 8306.335493160532, 8319.916800831974, 8362.749117526484, 9188.540572971324, 1759.129231030648, 1820.0444766497562, 1836.182898610119, 1841.9355322563647, 1911.9426417207505, 60316.698416507636, 61157.38842611561, 61606.38393616051, 62146.37853621451, 67576.0, 8554.401191916631, 9188.0, 9491.430514169171, 10011.249156313306, 10024.599016039423, 11806.5867694632, 12579.245245285128, 12580.87419125797, 12828.871711282954, 12965.546205882749, 6518.217813862367, 6547.9017814732815, 6920.9307906920285, 6960.65196740161, 7129.465290103263, 6256.905041617699, 6360.777372792023, 6367.522435817332, 6425.1968503937005, 6791.252962174157, 4053.574374690674, 4119.588041195916, 4194.224068547329, 4233.597808208216, 4408.360787685768, 1712.6317841664102, 1727.7322015087682, 1753.4651931161118, 1802.9459116226535, 1817.6092140189924, 840.1808236968907, 849.8427790858711, 861.978450538729, 896.3546246702364, 917.6237742525533, 15806.36774529029, 15840.52478425626, 16190.676186476205, 16453.91773041127, 16617.252223649964, 821.8849361089452, 831.5052543736467, 846.593635055177, 849.4945507423073, 897.533282692996, 8901.866472002925, 8955.149260820212, 8961.327900407496, 9264.39781414206, 9352.67265645699, 380263.0, 395268.0, 401058.0, 401466.0, 406890.0, 70965.64517177381, 72830.90753638699, 75926.62036689892, 77008.61495692485, 77014.22985770182, 875.7170548356329, 886.8403687336341, 887.5784002598714, 901.315000599536, 905.4748246017426, 14115.717685646436, 14340.784888226894, 14383.928080359532, 14462.349194286493, 14801.333939972512, 27379.31551711223, 29837.0, 34716.652833471344, 35694.464583030756, 35805.46291805573, 43905.56094439015, 47340.7632961833, 48346.0, 49077.0, 49711.75144124256, 7278.67245973943, 7485.139208991036, 7524.134724506752, 7600.923990760021, 7710.92289077102, 10812.29665095686, 10868.293560918515, 11305.26515776472, 11426.25729327591, 11615.0, 9178.495182765013, 9645.807083858143, 9653.758656033655, 9939.801203976025, 10046.0, 9649.0, 9946.303758736818, 10279.074883260542, 10335.17318614494, 10340.586376544854, 9782.9021709782, 9795.461249631338, 9978.401295922267, 10086.629642034262, 10096.29325947206, 7288.380487658608, 7319.597422141834, 7387.8153046174275, 7439.55362678241, 7494.70021199146, 8553.64233125183, 9649.372790768577, 10007.899921000988, 10047.246456515644, 10259.230557708352], "value": 7818.3768953328345, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1212.812014137809, 1375.154280117728, 1421.6516953346431, 1467.9926600366996, 1702.3190723710507, 6668.299828518008, 7390.963045184773, 7681.23187681233, 7686.5772382518935, 7915.248051435119, 7678.500897441676, 7818.413618978585, 7836.843263134748, 7838.294553490185, 7838.608069596512, 2189.9671504927437, 2216.4015715756736, 2219.323106452531, 2219.966700499486, 2223.1329781385193, 2134.2210093315907, 2156.223759446606, 2190.671399290103, 2194.813440857529, 2196.253273886878, 2154.0, 2155.256436529403, 2159.373781603333, 2165.72928383952, 2209.1384360099573, 2164.145162660756, 2166.447555873258, 2169.349195241421, 2172.3374370816896, 2173.3262688566597, 2104.947376315589, 2119.9576008479744, 2142.8821414822332, 2145.249162793026, 2147.4953385954286, 7430.256974302581, 7709.344705700022, 7768.65041073153, 7791.649375778104, 7832.2559356861575, 4515.525869783658, 4620.237660785966, 4624.630029597658, 4627.606653434428, 4632.62938964882, 2738.4523095380805, 2814.5074611942828, 2883.1927060422904, 2901.0, 2906.985465072661, 866.9436486628349, 868.452874688942, 870.1124852650262, 883.9734807955772, 891.5809569502323, 10004.94997525015, 10022.298439109265, 10047.748806279827, 10061.547230374577, 10075.949620251926, 3778.7543809652284, 3864.478295430081, 3892.2799282132896, 3928.332183528807, 3932.33150364433, 7842.215778422226, 7844.215578442224, 7885.921140788519, 7898.328642065489, 7920.2871741542385, 4341.544137865541, 4353.238183317907, 4355.934660980088, 4361.454818147731, 4423.73457592539, 3621.3662609043313, 4047.453593764863, 4059.5534491205945, 4098.995746042179, 4268.189044081614, 3615.7107431405425, 3626.165981824195, 3714.0343510687417, 3740.831662575191, 3775.093977445393, 1166.7374840660952, 1199.2264989081993, 1223.9632811015683, 1225.975480490403, 1232.580922486341, 823.6129019360891, 834.1366685480499, 843.8438888805467, 851.7189327521903, 854.1629203380721, 5013.974930125399, 5109.489051094861, 5124.615653825978, 5311.548518375904, 5343.893122137535, 865.8354912566592, 880.6081293824315, 917.9954100229456, 931.3387494878712, 956.799072194833, 3380.7802492837886, 3599.6580324869096, 3757.5866654667966, 3768.8869333920024, 3797.6392242736897, 9153.084691530927, 9373.718788436357, 9407.858882116774, 9442.15020648145, 9643.373180743369, 8638.17937295956, 8962.058983806737, 9001.684941027033, 9384.90615093854, 9400.95299523498, 6919.654017299215, 7134.714611415485, 7294.343509084208, 7307.3058059484265, 7309.049823522908, 5150.793968241228, 5183.92224116631, 5265.473452654704, 5457.890842183214, 5565.193047008081, 8529.872051919103, 8697.739067827975, 8824.558772061242, 8876.556172191495, 8887.422317549339, 8713.52075635846, 9041.23149532297, 9077.818443631222, 9227.953860230657, 9278.257739380699, 6534.509911756732, 6646.468282537288, 7097.219305876246, 7324.377427918686, 7441.404687625085, 7378.483506154517, 7543.622818858926, 7605.505642133244, 7605.6577454013595, 7608.353289970193, 6257.092721555258, 6491.058796474579, 6585.275619681927, 6715.731370745116, 6736.7642132525125, 6499.350064993463, 6543.018547217765, 6573.934260657332, 6588.275289718032, 6656.434203092791, 6148.108524263867, 6205.906911396423, 6227.1282020516255, 6234.501239900706, 6238.064290356483, 4062.6952978526137, 4151.1697660468635, 4240.978795106004, 4241.321388577812, 4269.40228368035, 4252.149570085933, 4300.935485967776, 4346.065595896897, 4403.8458653946955, 4413.757243351518], "value": 4064.6704848567474, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.3959153531029, 300.787944499128, 302.8304149676183, 308.7298613713001, 313.6346156727412, 4641.582257596816, 4693.694909830863, 4717.198076327024, 4726.598239149676, 4744.9323902122005, 4592.173408786417, 4603.355530225771, 4634.467036290827, 4668.603168730662, 4694.17851875922, 3523.4714792781033, 3613.981930090359, 3624.2932628137523, 3700.2044560419504, 3743.4010558310674, 3670.8348124334334, 3687.15195505034, 3706.4996225509617, 3709.833057512405, 3714.164313029574, 3509.842057107436, 3670.687991520724, 3676.5588129424373, 3679.675316885919, 3692.519972403584, 3581.2658405026905, 3637.836297366625, 3685.649863262986, 3689.760165589228, 3701.81490925454, 3271.4460631200227, 3284.458064419368, 3286.0963235110535, 3286.7370610351113, 3305.834708264601, 4561.201789686825, 4583.381243532114, 4585.180114229758, 4588.7476188809605, 4591.586757191869, 3924.725269231155, 4200.474940632421, 4210.347396153601, 4307.763073030982, 4308.9353659695125, 4911.901761964777, 4919.2867034279825, 4932.0, 4943.258511223309, 4958.330625365602, 3498.5306171407815, 3514.5431093957877, 3514.7715398499017, 3519.6449366993893, 3541.5750109986707, 5007.824726134568, 5119.462456442057, 5140.126178549618, 5152.587792976553, 5159.974200129013, 3546.432570788713, 3577.94633080504, 3589.515415418937, 3599.4240921452442, 3624.927501450009, 3636.7272454566014, 3641.089727568108, 3646.5077214575695, 3647.507586475845, 3650.105724097613, 4166.937430955087, 4170.4995400552125, 4187.937180942288, 4192.727472714264, 4194.224068547329, 4586.637881490968, 4852.17513022787, 4864.221724524059, 4932.26016097581, 4959.380077490314, 1261.3314943080181, 1269.936503174856, 1277.2911034376011, 1279.3411393132544, 1281.6603600045996, 803.1446509467398, 828.8963879515061, 866.9812969760507, 885.4156256870434, 890.3767362846033, 4382.189294980439, 4392.890177745582, 4406.334167828418, 4413.646908247332, 4420.369879921442, 5030.949690503048, 5031.572316353077, 5074.086664400444, 5121.61587880903, 5149.201873709506, 4225.788710564521, 4317.222899878052, 4335.1329734053315, 4357.477102747627, 4377.868663940087, 2650.4831557846037, 2658.3752818087733, 2664.200739778059, 2694.461107778452, 2699.25770413138, 4732.668713190113, 4760.928586071212, 4761.619070474354, 4763.476017638057, 4764.475907650156, 4938.629602779806, 5123.3339665843205, 5136.614753893398, 5139.357580302462, 5149.691018538825, 4756.999999999931, 4760.214564596803, 4785.186518291899, 4788.401449818773, 4792.329073929653, 5028.899422011613, 5029.7736601852275, 5034.421041580265, 5041.0, 5071.898562028812, 4799.208130658402, 4848.248521479177, 4932.8766780830765, 4948.257761335684, 4955.339961112923, 4710.458297295855, 4711.363965864564, 4737.957649317144, 4761.381130415675, 4766.7854946526795, 3545.2377738786286, 3548.6983606392714, 3558.91102722434, 3566.0, 3581.641835816502, 4837.186055229289, 4965.205567109245, 5007.84976450707, 5108.61685373591, 5122.616893438825, 4925.655204135676, 4955.876103097451, 4977.954629527695, 5017.37282839645, 5027.723475208899, 4972.950270497248, 4988.927380613186, 5035.723035232951, 5039.0, 5052.267421223828, 4793.424789025339, 4950.257461380822, 4977.8257760978195, 4978.626603004862, 4992.751812046989, 4813.927791083067, 4890.217565189552, 5018.899622007466, 5037.19404895215, 5039.874003149951, 4890.951090489049, 4929.334539837076, 4945.530174633404, 4947.901041979068, 4964.67729597575], "value": 3725.3074220348058, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.4115325827432, 643.1285608001156, 674.8549061951679, 688.8140202145421, 745.4595418321718, 13160.802587961176, 14151.716965660693, 14159.716805663906, 14166.362513686883, 14195.787063194035, 12867.613971580868, 13058.216507009585, 13604.863951360509, 13612.387442565083, 13612.795808062887, 633.3571425003602, 787.6022608582658, 806.5322113174374, 806.6289506826866, 810.3193317613204, 771.5949126708495, 801.9919800801975, 809.3808236698901, 810.0198759501011, 810.5582457560644, 843.0515669871395, 844.6410275632832, 846.2299307630058, 846.5047946951041, 846.9280111190557, 641.0768493369579, 809.401043228007, 841.5203334099566, 841.9747407577782, 842.3724325377572, 597.6712807955607, 597.8176656119882, 597.8385835824303, 598.2193237824637, 611.5474548833876, 11843.940780296129, 11856.822147667792, 11877.346745928971, 11896.762064758655, 11919.165658403916, 12846.421911014028, 13443.798343024862, 13492.0, 13494.122882012736, 13681.863181368159, 11630.6510804676, 11745.412729363497, 11749.177557570973, 11851.229670071503, 12049.93975030128, 4180.665546756252, 4182.456280683522, 4225.154969006211, 4234.5765423457715, 4239.639630631369, 63729.68135159341, 66369.0, 67989.66005170041, 68586.0, 68667.65666171638, 10597.311174773615, 10701.197410194267, 10816.621418250323, 10888.891111088946, 10909.509072091774, 20459.07934142967, 20464.59070818575, 20468.283610073577, 20486.05382488661, 20493.18027278922, 6838.111045564045, 6959.965200173967, 7007.92992070083, 7024.2273349931465, 7133.144022717307, 3701.4447832824753, 3709.0541911812315, 3726.347889119393, 3755.9248815023548, 3791.563970143469, 2818.3376906426975, 2944.1167649705003, 2948.8820447181874, 2954.1137658702733, 2959.467295886718, 497.2019908047604, 571.7370009795458, 598.3507893935042, 609.1836938502428, 615.689077016104, 452.181551391975, 547.8356493052069, 565.6464709556527, 585.2713371852071, 589.7228302697725, 7666.731664391719, 7907.428144830161, 7992.0, 8647.221750042401, 8742.30061595071, 373.0598890795181, 373.534948988511, 438.2965340628309, 448.13062658442516, 468.85131428001534, 4039.555648878621, 4297.247981603207, 4408.779561021936, 4439.0, 4546.18041000458, 99918.0, 101840.0, 102155.48922255341, 103246.48376758219, 104476.47761761295, 89209.0, 91404.0, 91745.0, 91858.54070729604, 94420.52789736145, 81206.18793812105, 81272.0, 81627.0, 82572.76140857892, 84157.0, 8657.307415406627, 8730.912690873263, 8818.470891746516, 8993.865092023756, 9053.230475409664, 44043.55956440394, 44734.0, 45209.09581808411, 45361.0, 45874.0, 63499.36500634935, 65503.67248163729, 65556.0, 65896.67051664711, 66320.0, 11174.27367221128, 11267.7746445069, 11435.714107147387, 11592.188546801643, 11676.241044332091, 12847.807282890952, 12899.484020639069, 13072.411741471848, 13189.0, 13601.183928964294, 12354.938225308815, 12539.498420063095, 12578.748425031632, 12860.0, 12976.15654982423, 12488.625341239414, 12491.437885295365, 12510.562130325396, 12714.364281785689, 12799.513803788052, 11834.940825295818, 11898.464569094585, 11903.82144267854, 11949.522019119486, 11981.880181198076, 9718.805623887623, 9755.170810480904, 9981.351212171186, 10018.298719089886, 10018.549165287433, 9593.42439453635, 10118.711418788776, 10135.54390052464, 10228.07947284748, 10232.59069637236], "value": 6761.363659395137, "unit": "score"}], "name": "dromaeo_css", "value": 6340.923926675358, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 112.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 130.0, 137.0, 181.0, 130.0, 132.0, 152.0, 130.0, 166.0, 130.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 162.0, 160.0, 161.0, 159.0, 162.0, 161.0, 160.0, 163.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 80.0, 104.0, 76.0, 77.0, 76.0, 75.0, 104.0, 80.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 114.0, 86.0, 92.0, 88.0, 113.0, 86.0, 87.0, 119.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 148.0, 148.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 64.0, 73.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 67.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 90.0, 84.0, 84.0, 87.0, 84.0, 118.0, 90.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 133.0, 131.0, 139.0, 130.0, 129.0, 132.0, 161.0, 136.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 172.0, 170.0, 169.0, 168.0, 169.0, 168.0, 167.0, 167.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 67.0, 65.0, 66.0, 66.0, 67.0, 67.0, 66.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1546.2, "alertThreshold": 2.0}]} 04:30:21 INFO - Return code: 0 04:30:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:30:22 INFO - # TBPL SUCCESS # 04:30:22 INFO - Running post-action listener: _resource_record_post_action 04:30:22 INFO - ##### 04:30:22 INFO - ##### Finished run-tests step (success) 04:30:22 INFO - ##### 04:30:22 INFO - Running post-run listener: _resource_record_post_run 04:30:22 INFO - Total resource usage - Wall time: 1207s; CPU: 13.0%; Read bytes: 180224; Write bytes: 294305792; Read time: 160; Write time: 539336 04:30:22 INFO - TinderboxPrint: CPU usage
13.2% 04:30:22 INFO - TinderboxPrint: I/O read bytes / time
180,224 / 160 04:30:22 INFO - TinderboxPrint: I/O write bytes / time
294,305,792 / 539,336 04:30:22 INFO - TinderboxPrint: CPU idle
8,358.6 (86.8%) 04:30:22 INFO - TinderboxPrint: CPU user
1,215.1 (12.6%) 04:30:22 INFO - TinderboxPrint: Swap in / out
0 / 0 04:30:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1441792; Read time: 0; Write time: 120 04:30:22 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 180224; Write bytes: 292864000; Read time: 160; Write time: 539216 04:30:22 INFO - Running post-run listener: _upload_blobber_files 04:30:22 INFO - Blob upload gear active. 04:30:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:30:22 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 04:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:30:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:30:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:30:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:30:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:23 INFO - (blobuploader) - INFO - Done attempting. 04:30:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:30:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:24 INFO - (blobuploader) - INFO - Done attempting. 04:30:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:30:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:30:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:29 INFO - (blobuploader) - INFO - Done attempting. 04:30:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:30:29 INFO - Return code: 0 04:30:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:30:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:30:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c47573682704c66e4b6341ba0c361c3cf6abb5fe3a62d59ea0dda1fc66bab29600d02a3de108e701ddc481a2b170c973040f1e430098f999db164a754fff7368", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f03088edbcceaeadb56628e7df32ecf71148979a336fe40a2c2c90628163deadf9ade684ad677179df68cdaf8ad5c6880f5f5548ccd01c2d4e023e8f9b346210", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6c902ae2318a24279cefbad3a970cfa699485fdf1145a7d46572421567346ba2c02a0103e0d8f67dbbbb74f0eb8e15175e909447184fa6101f15b73dcb899748"} 04:30:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:30:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:30:29 INFO - Contents: 04:30:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c47573682704c66e4b6341ba0c361c3cf6abb5fe3a62d59ea0dda1fc66bab29600d02a3de108e701ddc481a2b170c973040f1e430098f999db164a754fff7368", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f03088edbcceaeadb56628e7df32ecf71148979a336fe40a2c2c90628163deadf9ade684ad677179df68cdaf8ad5c6880f5f5548ccd01c2d4e023e8f9b346210", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6c902ae2318a24279cefbad3a970cfa699485fdf1145a7d46572421567346ba2c02a0103e0d8f67dbbbb74f0eb8e15175e909447184fa6101f15b73dcb899748"} 04:30:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:30:29 INFO - Copying logs to upload dir... 04:30:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:30:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.334585 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-06-21 04:30:29.706017) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 04:30:29.709869) ========= 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=5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c47573682704c66e4b6341ba0c361c3cf6abb5fe3a62d59ea0dda1fc66bab29600d02a3de108e701ddc481a2b170c973040f1e430098f999db164a754fff7368", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f03088edbcceaeadb56628e7df32ecf71148979a336fe40a2c2c90628163deadf9ade684ad677179df68cdaf8ad5c6880f5f5548ccd01c2d4e023e8f9b346210", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6c902ae2318a24279cefbad3a970cfa699485fdf1145a7d46572421567346ba2c02a0103e0d8f67dbbbb74f0eb8e15175e909447184fa6101f15b73dcb899748"} build_url:https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/VklF6A5RSP6G--lh12uYIQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c47573682704c66e4b6341ba0c361c3cf6abb5fe3a62d59ea0dda1fc66bab29600d02a3de108e701ddc481a2b170c973040f1e430098f999db164a754fff7368", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f03088edbcceaeadb56628e7df32ecf71148979a336fe40a2c2c90628163deadf9ade684ad677179df68cdaf8ad5c6880f5f5548ccd01c2d4e023e8f9b346210", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6c902ae2318a24279cefbad3a970cfa699485fdf1145a7d46572421567346ba2c02a0103e0d8f67dbbbb74f0eb8e15175e909447184fa6101f15b73dcb899748"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 04:30:29.739787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:30:29.740104) ========= 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=5cf47e208febbcd81da23b2e0000026f-1466506441.29144-1044975580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004566 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 04:30:29.835043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 04:30:29.835366) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 04:30:29.835688) ========= ========= Total master_lag: 0.19 =========