builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1450375177.55 results: success (0) buildid: 20151217072309 builduid: 23de7403ed1646b4a075de106e17f9d1 revision: 0711218a018d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.550856) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.551354) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.551641) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006413 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.576612) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.576976) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.600480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.600830) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.655305) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.655586) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.655923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.656204) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 09:59:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2015-12-17 09:59:38 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135032 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.807387) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.807664) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021943 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.863364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.863677) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 09:59:38,404 Setting DEBUG logging. 2015-12-17 09:59:38,404 attempt 1/10 2015-12-17 09:59:38,404 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0711218a018d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 09:59:38,692 unpacking tar archive at: mozilla-central-0711218a018d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.585096 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.463469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.463788) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.482209) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.482625) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.483122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2015-12-17 09:59:38.483428) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (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', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:59:39 INFO - MultiFileLogger online at 20151217 09:59:39 in /builds/slave/test-pgo 09:59:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:59:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:59:39 INFO - {'append_to_log': False, 09:59:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:59:39 INFO - 'blob_upload_branch': 'Firefox', 09:59:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:59:39 INFO - 'branch': 'Firefox', 09:59:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:39 INFO - 'config_files': ('talos/linux_config.py',), 09:59:39 INFO - 'default_actions': ('clobber', 09:59:39 INFO - 'read-buildbot-config', 09:59:39 INFO - 'download-and-extract', 09:59:39 INFO - 'populate-webroot', 09:59:39 INFO - 'create-virtualenv', 09:59:39 INFO - 'install', 09:59:39 INFO - 'run-tests'), 09:59:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:39 INFO - 'download_minidump_stackwalk': True, 09:59:39 INFO - 'download_symbols': 'ondemand', 09:59:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:59:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:59:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:59:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:59:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:39 INFO - 'installer_path': 'installer.exe', 09:59:39 INFO - 'log_level': 'info', 09:59:39 INFO - 'log_name': 'talos', 09:59:39 INFO - 'log_to_console': True, 09:59:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:59:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:59:39 INFO - 'opt_config_files': (), 09:59:39 INFO - 'pip_index': False, 09:59:39 INFO - 'sps_profile': False, 09:59:39 INFO - 'sps_profile_interval': 0, 09:59:39 INFO - 'suite': 'dromaeojs', 09:59:39 INFO - 'system_bits': '64', 09:59:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:39 INFO - 'title': 'talos-linux64-ix-013', 09:59:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:59:39 INFO - 'use_talos_json': True, 09:59:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:59:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:39 INFO - 'work_dir': 'build'} 09:59:39 INFO - ##### 09:59:39 INFO - ##### Running clobber step. 09:59:39 INFO - ##### 09:59:39 INFO - Running pre-action listener: _resource_record_pre_action 09:59:39 INFO - Running main action method: clobber 09:59:39 INFO - rmtree: /builds/slave/test-pgo/build 09:59:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:59:40 INFO - Running post-action listener: _resource_record_post_action 09:59:40 INFO - ##### 09:59:40 INFO - ##### Running read-buildbot-config step. 09:59:40 INFO - ##### 09:59:40 INFO - Running pre-action listener: _resource_record_pre_action 09:59:40 INFO - Running main action method: read_buildbot_config 09:59:40 INFO - Using buildbot properties: 09:59:40 INFO - { 09:59:40 INFO - "project": "", 09:59:40 INFO - "product": "firefox", 09:59:40 INFO - "script_repo_revision": "production", 09:59:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:59:40 INFO - "repository": "", 09:59:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:59:40 INFO - "buildid": "20151217072309", 09:59:40 INFO - "stage_platform": "linux64-pgo", 09:59:40 INFO - "basedir": "/builds/slave/test-pgo", 09:59:40 INFO - "nightly_build": "True", 09:59:40 INFO - "buildnumber": 606, 09:59:40 INFO - "slavename": "talos-linux64-ix-013", 09:59:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:59:40 INFO - "platform": "ubuntu64_hw", 09:59:40 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:59:40 INFO - "branch": "mozilla-central", 09:59:40 INFO - "repo_path": "mozilla-central", 09:59:40 INFO - "revision": "0711218a018d", 09:59:40 INFO - "pgo_build": "True", 09:59:40 INFO - "builduid": "23de7403ed1646b4a075de106e17f9d1", 09:59:40 INFO - "slavebuilddir": "test" 09:59:40 INFO - } 09:59:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 09:59:40 INFO - Running post-action listener: _resource_record_post_action 09:59:40 INFO - ##### 09:59:40 INFO - ##### Running download-and-extract step. 09:59:40 INFO - ##### 09:59:40 INFO - Running pre-action listener: _resource_record_pre_action 09:59:40 INFO - Running main action method: download_and_extract 09:59:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:59:40 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')]} 09:59:40 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:59:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json 09:59:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json 09:59:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:59:43 INFO - Downloaded 1302 bytes. 09:59:43 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:59:43 INFO - Using the following test package requirements: 09:59:43 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:43 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:59:43 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'jsshell-linux-x86_64.zip'], 09:59:43 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:59:43 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:43 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:59:43 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:59:43 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:59:43 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:43 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:59:43 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:59: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')]} 09:59:43 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:59:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:59:46 INFO - Downloaded 23120898 bytes. 09:59:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:59:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:59:47 INFO - Return code: 0 09:59:47 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:47 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:59:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:59:49 INFO - Downloaded 23120898 bytes. 09:59:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:59:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:59:51 INFO - Return code: 0 09:59: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')]} 09:59:51 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:59:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:59:53 INFO - Downloaded 11132039 bytes. 09:59:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:59:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:59:53 INFO - Return code: 0 09:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:53 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:59:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:59:54 INFO - Downloaded 54646150 bytes. 09:59:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:54 INFO - mkdir: /builds/slave/test-pgo/properties 09:59:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:59:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:59:54 INFO - Contents: 09:59:54 INFO - build_url:https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:54 INFO - Running post-action listener: _resource_record_post_action 09:59:54 INFO - Running post-action listener: set_extra_try_arguments 09:59:54 INFO - ##### 09:59:54 INFO - ##### Running populate-webroot step. 09:59:54 INFO - ##### 09:59:54 INFO - Running pre-action listener: _resource_record_pre_action 09:59:54 INFO - Running main action method: populate_webroot 09:59:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:54 INFO - '--tppagecycles', 09:59:54 INFO - '7'], 09:59:54 INFO - 'tests': ['tsvgm']}}, 09:59:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:59:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:59:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:59:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'tests': ['damp', 'tps']}, 09:59:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['damp', 'tps']}, 09:59:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:59:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:54 INFO - 'other': {'tests': ['a11yr', 09:59:54 INFO - 'ts_paint', 09:59:54 INFO - 'tpaint', 09:59:54 INFO - 'sessionrestore', 09:59:54 INFO - 'sessionrestore_no_auto_restore']}, 09:59:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['a11yr', 09:59:54 INFO - 'ts_paint', 09:59:54 INFO - 'tpaint', 09:59:54 INFO - 'sessionrestore', 09:59:54 INFO - 'sessionrestore_no_auto_restore']}, 09:59:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['a11yr', 09:59:54 INFO - 'ts_paint', 09:59:54 INFO - 'tpaint', 09:59:54 INFO - 'sessionrestore', 09:59:54 INFO - 'sessionrestore_no_auto_restore']}, 09:59:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['a11yr', 09:59:54 INFO - 'ts_paint', 09:59:54 INFO - 'tpaint', 09:59:54 INFO - 'sessionrestore', 09:59:54 INFO - 'sessionrestore_no_auto_restore']}, 09:59:54 INFO - 'other_l64': {'tests': ['a11yr', 09:59:54 INFO - 'ts_paint', 09:59:54 INFO - 'tpaint', 09:59:54 INFO - 'sessionrestore', 09:59:54 INFO - 'sessionrestore_no_auto_restore']}, 09:59:54 INFO - 'other_nol64': {'tests': ['a11yr', 09:59:54 INFO - 'ts_paint', 09:59:54 INFO - 'tpaint', 09:59:54 INFO - 'sessionrestore', 09:59:54 INFO - 'sessionrestore_no_auto_restore']}, 09:59:54 INFO - 'svgr': {'tests': ['tsvgx', 09:59:54 INFO - 'tsvgr_opacity', 09:59:54 INFO - 'tart', 09:59:54 INFO - 'tscrollx', 09:59:54 INFO - 'cart']}, 09:59:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['tsvgx', 09:59:54 INFO - 'tsvgr_opacity', 09:59:54 INFO - 'tart', 09:59:54 INFO - 'tscrollx', 09:59:54 INFO - 'cart']}, 09:59:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'tests': ['tp5o']}, 09:59:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'talos_options': ['--e10s'], 09:59:54 INFO - 'tests': ['tp5o']}, 09:59:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'talos_options': ['--xperf_path', 09:59:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:54 INFO - 'tests': ['tp5n']}, 09:59:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:54 INFO - 'talos_options': ['--e10s', 09:59:54 INFO - '--xperf_path', 09:59:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:54 INFO - 'tests': ['tp5n']}}, 09:59:54 INFO - 'talos.zip': {'path': '', 09:59:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:54 INFO - Running post-action listener: _resource_record_post_action 09:59:54 INFO - ##### 09:59:54 INFO - ##### Running create-virtualenv step. 09:59:55 INFO - ##### 09:59:55 INFO - Running pre-action listener: _resource_record_pre_action 09:59:55 INFO - Running main action method: create_virtualenv 09:59:55 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:59:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:59:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:59:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:55 INFO - Using real prefix '/usr' 09:59:55 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:59:55 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:57 INFO - Installing pip.................done. 09:59:57 INFO - Return code: 0 09:59:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:59:57 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')]} 09:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:57 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')]} 09:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 09:59:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 09:59:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 09:59:57 INFO - Using env: {'DISPLAY': ':0', 09:59:57 INFO - 'HOME': '/home/cltbld', 09:59:57 INFO - 'LANG': 'en_US.UTF-8', 09:59:57 INFO - 'LANGUAGE': 'en_US:en', 09:59:57 INFO - 'LOGNAME': 'cltbld', 09:59:57 INFO - 'MAIL': '/var/mail/cltbld', 09:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:57 INFO - 'MOZ_NO_REMOTE': '1', 09:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:57 INFO - 'NO_EM_RESTART': '1', 09:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:59:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:59:57 INFO - 'SHELL': '/bin/bash', 09:59:57 INFO - 'SHLVL': '1', 09:59:57 INFO - 'TERM': 'linux', 09:59:57 INFO - 'TMOUT': '86400', 09:59:57 INFO - 'USER': 'cltbld', 09:59:57 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 09:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:57 INFO - '_': '/tools/buildbot/bin/python'} 09:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:57 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 09:59:57 INFO - Cleaning up... 09:59:57 INFO - Return code: 0 09:59:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:59:57 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')]} 09:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:57 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')]} 09:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 09:59:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test-pgo/build 09:59:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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>=0.7.1 09:59:57 INFO - Using env: {'DISPLAY': ':0', 09:59:57 INFO - 'HOME': '/home/cltbld', 09:59:57 INFO - 'LANG': 'en_US.UTF-8', 09:59:57 INFO - 'LANGUAGE': 'en_US:en', 09:59:57 INFO - 'LOGNAME': 'cltbld', 09:59:57 INFO - 'MAIL': '/var/mail/cltbld', 09:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:57 INFO - 'MOZ_NO_REMOTE': '1', 09:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:57 INFO - 'NO_EM_RESTART': '1', 09:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:59:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:59:57 INFO - 'SHELL': '/bin/bash', 09:59:57 INFO - 'SHLVL': '1', 09:59:57 INFO - 'TERM': 'linux', 09:59:57 INFO - 'TMOUT': '86400', 09:59:57 INFO - 'USER': 'cltbld', 09:59:57 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 09:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:57 INFO - '_': '/tools/buildbot/bin/python'} 09:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:57 INFO - Downloading/unpacking psutil>=0.7.1 09:59: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 09:59: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 09:59: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 09:59: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 09:59: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 09:59: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 09:59:59 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 09:59:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:59:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:59 INFO - Installing collected packages: psutil 09:59:59 INFO - Running setup.py install for psutil 09:59:59 INFO - building 'psutil._psutil_linux' extension 09:59:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:00:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:00:00 INFO - building 'psutil._psutil_posix' extension 10:00:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:00:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:00:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:00:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:00:00 INFO - Successfully installed psutil 10:00:00 INFO - Cleaning up... 10:00:00 INFO - Return code: 0 10:00:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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.0.0'] in /builds/slave/test-pgo/build 10:00:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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.0.0 10:00:00 INFO - Using env: {'DISPLAY': ':0', 10:00:00 INFO - 'HOME': '/home/cltbld', 10:00:00 INFO - 'LANG': 'en_US.UTF-8', 10:00:00 INFO - 'LANGUAGE': 'en_US:en', 10:00:00 INFO - 'LOGNAME': 'cltbld', 10:00:00 INFO - 'MAIL': '/var/mail/cltbld', 10:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:00 INFO - 'MOZ_NO_REMOTE': '1', 10:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:00 INFO - 'NO_EM_RESTART': '1', 10:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:00 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:00 INFO - 'SHELL': '/bin/bash', 10:00:00 INFO - 'SHLVL': '1', 10:00:00 INFO - 'TERM': 'linux', 10:00:00 INFO - 'TMOUT': '86400', 10:00:00 INFO - 'USER': 'cltbld', 10:00:00 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:00 INFO - '_': '/tools/buildbot/bin/python'} 10:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:00:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 10:00:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:00:02 INFO - Installing collected packages: mozsystemmonitor 10:00:02 INFO - Running setup.py install for mozsystemmonitor 10:00:02 INFO - Successfully installed mozsystemmonitor 10:00:02 INFO - Cleaning up... 10:00:02 INFO - Return code: 0 10:00:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 10:00:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:00:02 INFO - Using env: {'DISPLAY': ':0', 10:00:02 INFO - 'HOME': '/home/cltbld', 10:00:02 INFO - 'LANG': 'en_US.UTF-8', 10:00:02 INFO - 'LANGUAGE': 'en_US:en', 10:00:02 INFO - 'LOGNAME': 'cltbld', 10:00:02 INFO - 'MAIL': '/var/mail/cltbld', 10:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:02 INFO - 'MOZ_NO_REMOTE': '1', 10:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:02 INFO - 'NO_EM_RESTART': '1', 10:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:02 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:02 INFO - 'SHELL': '/bin/bash', 10:00:02 INFO - 'SHLVL': '1', 10:00:02 INFO - 'TERM': 'linux', 10:00:02 INFO - 'TMOUT': '86400', 10:00:02 INFO - 'USER': 'cltbld', 10:00:02 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:02 INFO - '_': '/tools/buildbot/bin/python'} 10:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00:04 INFO - Downloading blobuploader-1.2.4.tar.gz 10:00:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 10:00:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:00:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 10:00:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:00:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - Downloading docopt-0.6.1.tar.gz 10:00:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 10:00:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:00:05 INFO - Installing collected packages: blobuploader, requests, docopt 10:00:05 INFO - Running setup.py install for blobuploader 10:00:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:00:05 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:00:05 INFO - Running setup.py install for requests 10:00:05 INFO - Running setup.py install for docopt 10:00:05 INFO - Successfully installed blobuploader requests docopt 10:00:05 INFO - Cleaning up... 10:00:05 INFO - Return code: 0 10:00:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 10:00:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:00:05 INFO - Using env: {'DISPLAY': ':0', 10:00:05 INFO - 'HOME': '/home/cltbld', 10:00:05 INFO - 'LANG': 'en_US.UTF-8', 10:00:05 INFO - 'LANGUAGE': 'en_US:en', 10:00:05 INFO - 'LOGNAME': 'cltbld', 10:00:05 INFO - 'MAIL': '/var/mail/cltbld', 10:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:05 INFO - 'MOZ_NO_REMOTE': '1', 10:00:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:05 INFO - 'NO_EM_RESTART': '1', 10:00:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:05 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:05 INFO - 'SHELL': '/bin/bash', 10:00:05 INFO - 'SHLVL': '1', 10:00:05 INFO - 'TERM': 'linux', 10:00:05 INFO - 'TMOUT': '86400', 10:00:05 INFO - 'USER': 'cltbld', 10:00:05 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:05 INFO - '_': '/tools/buildbot/bin/python'} 10:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:05 INFO - Running setup.py (path:/tmp/pip-RWyqNh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:05 INFO - Running setup.py (path:/tmp/pip-JqAiGZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:05 INFO - Running setup.py (path:/tmp/pip-LRFw21-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:06 INFO - Running setup.py (path:/tmp/pip-Uumlhp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:06 INFO - Running setup.py (path:/tmp/pip-Rsnksy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:06 INFO - Running setup.py (path:/tmp/pip-EdRdC_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:06 INFO - Running setup.py (path:/tmp/pip-771xGz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:06 INFO - Running setup.py (path:/tmp/pip-T4ldRN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:06 INFO - Running setup.py (path:/tmp/pip-VNhanF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:06 INFO - Running setup.py (path:/tmp/pip-efnxOF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:06 INFO - Running setup.py (path:/tmp/pip-Bm3lS0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:06 INFO - Running setup.py (path:/tmp/pip-TDJkJq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:06 INFO - Running setup.py (path:/tmp/pip-tSWp5q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:06 INFO - Running setup.py (path:/tmp/pip-rf9xxv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:06 INFO - Running setup.py (path:/tmp/pip-luNTMd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:06 INFO - Running setup.py (path:/tmp/pip-uMHPbp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:06 INFO - Running setup.py (path:/tmp/pip-HMKp6F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:00:07 INFO - Running setup.py install for manifestparser 10:00:07 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Running setup.py install for mozcrash 10:00:07 INFO - Running setup.py install for mozdebug 10:00:07 INFO - Running setup.py install for mozdevice 10:00:07 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Running setup.py install for mozfile 10:00:07 INFO - Running setup.py install for mozhttpd 10:00:07 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Running setup.py install for mozinfo 10:00:07 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Running setup.py install for mozInstall 10:00:07 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:00:07 INFO - Running setup.py install for mozleak 10:00:07 INFO - Running setup.py install for mozlog 10:00:08 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Running setup.py install for moznetwork 10:00:08 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Running setup.py install for mozprocess 10:00:08 INFO - Running setup.py install for mozprofile 10:00:08 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Running setup.py install for mozrunner 10:00:08 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Running setup.py install for mozscreenshot 10:00:08 INFO - Running setup.py install for moztest 10:00:08 INFO - Running setup.py install for mozversion 10:00:08 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:00:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:00:08 INFO - Cleaning up... 10:00:08 INFO - Return code: 0 10:00:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 10:00:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:00:08 INFO - Using env: {'DISPLAY': ':0', 10:00:08 INFO - 'HOME': '/home/cltbld', 10:00:08 INFO - 'LANG': 'en_US.UTF-8', 10:00:08 INFO - 'LANGUAGE': 'en_US:en', 10:00:08 INFO - 'LOGNAME': 'cltbld', 10:00:08 INFO - 'MAIL': '/var/mail/cltbld', 10:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:08 INFO - 'MOZ_NO_REMOTE': '1', 10:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:08 INFO - 'NO_EM_RESTART': '1', 10:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:08 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:08 INFO - 'SHELL': '/bin/bash', 10:00:08 INFO - 'SHLVL': '1', 10:00:08 INFO - 'TERM': 'linux', 10:00:08 INFO - 'TMOUT': '86400', 10:00:08 INFO - 'USER': 'cltbld', 10:00:08 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:08 INFO - '_': '/tools/buildbot/bin/python'} 10:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:09 INFO - Running setup.py (path:/tmp/pip-8Aq_mn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:09 INFO - Running setup.py (path:/tmp/pip-n8ncgt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:09 INFO - Running setup.py (path:/tmp/pip-Yk2R9l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:09 INFO - Running setup.py (path:/tmp/pip-bbCQfg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:09 INFO - Running setup.py (path:/tmp/pip-94WARe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:09 INFO - Running setup.py (path:/tmp/pip-h6xt8q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:09 INFO - Running setup.py (path:/tmp/pip-PVeMY5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:09 INFO - Running setup.py (path:/tmp/pip-Im7ErM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:09 INFO - Running setup.py (path:/tmp/pip-M86Kxp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:09 INFO - Running setup.py (path:/tmp/pip-RwunmI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:09 INFO - Running setup.py (path:/tmp/pip-cxIkAq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:09 INFO - Running setup.py (path:/tmp/pip-WCB3vv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 10:00:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:09 INFO - Running setup.py (path:/tmp/pip-DYTNUg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:10 INFO - Running setup.py (path:/tmp/pip-Z58aXe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:10 INFO - Running setup.py (path:/tmp/pip-AuHWZb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:10 INFO - Running setup.py (path:/tmp/pip-4GYtkK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:10 INFO - Running setup.py (path:/tmp/pip-zB0Zk5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:00:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:11 INFO - Downloading blessings-1.5.1.tar.gz 10:00:11 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 10:00:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:00:12 INFO - Installing collected packages: blessings 10:00:12 INFO - Running setup.py install for blessings 10:00:12 INFO - Successfully installed blessings 10:00:12 INFO - Cleaning up... 10:00:12 INFO - Return code: 0 10:00:12 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:00:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:00:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:00:12 INFO - Reading from file tmpfile_stdout 10:00:12 INFO - Current package versions: 10:00:12 INFO - argparse == 1.2.1 10:00:12 INFO - blessings == 1.5.1 10:00:12 INFO - blobuploader == 1.2.4 10:00:12 INFO - docopt == 0.6.1 10:00:12 INFO - manifestparser == 1.1 10:00:12 INFO - mozInstall == 1.12 10:00:12 INFO - mozcrash == 0.16 10:00:12 INFO - mozdebug == 0.1 10:00:12 INFO - mozdevice == 0.47 10:00:12 INFO - mozfile == 1.2 10:00:12 INFO - mozhttpd == 0.7 10:00:12 INFO - mozinfo == 0.9 10:00:12 INFO - mozleak == 0.1 10:00:12 INFO - mozlog == 3.1 10:00:12 INFO - moznetwork == 0.27 10:00:12 INFO - mozprocess == 0.22 10:00:12 INFO - mozprofile == 0.27 10:00:12 INFO - mozrunner == 6.11 10:00:12 INFO - mozscreenshot == 0.1 10:00:12 INFO - mozsystemmonitor == 0.0 10:00:12 INFO - moztest == 0.7 10:00:12 INFO - mozversion == 1.4 10:00:12 INFO - psutil == 3.1.1 10:00:12 INFO - requests == 1.2.3 10:00:12 INFO - wsgiref == 0.1.2 10:00:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 10:00:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:00:12 INFO - Using env: {'DISPLAY': ':0', 10:00:12 INFO - 'HOME': '/home/cltbld', 10:00:12 INFO - 'LANG': 'en_US.UTF-8', 10:00:12 INFO - 'LANGUAGE': 'en_US:en', 10:00:12 INFO - 'LOGNAME': 'cltbld', 10:00:12 INFO - 'MAIL': '/var/mail/cltbld', 10:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:12 INFO - 'MOZ_NO_REMOTE': '1', 10:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:12 INFO - 'NO_EM_RESTART': '1', 10:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:12 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:12 INFO - 'SHELL': '/bin/bash', 10:00:12 INFO - 'SHLVL': '1', 10:00:12 INFO - 'TERM': 'linux', 10:00:12 INFO - 'TMOUT': '86400', 10:00:12 INFO - 'USER': 'cltbld', 10:00:12 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:12 INFO - '_': '/tools/buildbot/bin/python'} 10:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:00:12 INFO - Cleaning up... 10:00:12 INFO - Return code: 0 10:00:12 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 10:00:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:00:12 INFO - Using env: {'DISPLAY': ':0', 10:00:12 INFO - 'HOME': '/home/cltbld', 10:00:12 INFO - 'LANG': 'en_US.UTF-8', 10:00:12 INFO - 'LANGUAGE': 'en_US:en', 10:00:12 INFO - 'LOGNAME': 'cltbld', 10:00:12 INFO - 'MAIL': '/var/mail/cltbld', 10:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:12 INFO - 'MOZ_NO_REMOTE': '1', 10:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:12 INFO - 'NO_EM_RESTART': '1', 10:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:12 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:12 INFO - 'SHELL': '/bin/bash', 10:00:12 INFO - 'SHLVL': '1', 10:00:12 INFO - 'TERM': 'linux', 10:00:12 INFO - 'TMOUT': '86400', 10:00:12 INFO - 'USER': 'cltbld', 10:00:12 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:12 INFO - '_': '/tools/buildbot/bin/python'} 10:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:12 INFO - Downloading/unpacking jsonschema 10:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:00:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 10:00:14 INFO - Downloading/unpacking functools32 (from jsonschema) 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00: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 10:00:14 INFO - Downloading functools32-3.2.3-2.tar.gz 10:00:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 10:00:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:00:14 INFO - warning: no files found matching '*.txt' 10:00:14 INFO - no previously-included directories found matching 'build' 10:00:14 INFO - no previously-included directories found matching 'dist' 10:00:14 INFO - no previously-included directories found matching '.git*' 10:00:14 INFO - Installing collected packages: jsonschema, functools32 10:00:14 INFO - Running setup.py install for functools32 10:00:14 INFO - warning: no files found matching '*.txt' 10:00:14 INFO - no previously-included directories found matching 'build' 10:00:14 INFO - no previously-included directories found matching 'dist' 10:00:14 INFO - no previously-included directories found matching '.git*' 10:00:14 INFO - Successfully installed jsonschema functools32 10:00:14 INFO - Cleaning up... 10:00:15 INFO - Return code: 0 10:00:15 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f92895eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ba8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2787600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:00:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 10:00:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:00:15 INFO - Using env: {'DISPLAY': ':0', 10:00:15 INFO - 'HOME': '/home/cltbld', 10:00:15 INFO - 'LANG': 'en_US.UTF-8', 10:00:15 INFO - 'LANGUAGE': 'en_US:en', 10:00:15 INFO - 'LOGNAME': 'cltbld', 10:00:15 INFO - 'MAIL': '/var/mail/cltbld', 10:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:15 INFO - 'MOZ_NO_REMOTE': '1', 10:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:15 INFO - 'NO_EM_RESTART': '1', 10:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:15 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:15 INFO - 'SHELL': '/bin/bash', 10:00:15 INFO - 'SHLVL': '1', 10:00:15 INFO - 'TERM': 'linux', 10:00:15 INFO - 'TMOUT': '86400', 10:00:15 INFO - 'USER': 'cltbld', 10:00:15 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:15 INFO - '_': '/tools/buildbot/bin/python'} 10:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:15 INFO - Downloading/unpacking flake8 10:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:00:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 10:00:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:00:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 10:00:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 10:00:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 10:00:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:00:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:00:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:00:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:00:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:00:17 INFO - no previously-included directories found matching 'docs/_build' 10:00:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:00:17 INFO - Running setup.py install for pep8 10:00:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:00:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:00:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:00:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:00:17 INFO - no previously-included directories found matching 'docs/_build' 10:00:17 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:00:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:00:17 INFO - Cleaning up... 10:00:17 INFO - Return code: 0 10:00:17 INFO - Running post-action listener: _resource_record_post_action 10:00:17 INFO - Running post-action listener: _start_resource_monitoring 10:00:17 INFO - Starting resource monitoring. 10:00:17 INFO - ##### 10:00:17 INFO - ##### Running install step. 10:00:17 INFO - ##### 10:00:17 INFO - Running pre-action listener: _resource_record_pre_action 10:00:17 INFO - Running main action method: install 10:00:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:00:17 INFO - Reading from file tmpfile_stdout 10:00:17 INFO - Detecting whether we're running mozinstall >=1.0... 10:00:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:00:18 INFO - Reading from file tmpfile_stdout 10:00:18 INFO - Output received: 10:00:18 INFO - Usage: mozinstall [options] installer 10:00:18 INFO - Options: 10:00:18 INFO - -h, --help show this help message and exit 10:00:18 INFO - -d DEST, --destination=DEST 10:00:18 INFO - Directory to install application into. [default: 10:00:18 INFO - "/builds/slave/test-pgo"] 10:00:18 INFO - --app=APP Application being installed. [default: firefox] 10:00:18 INFO - mkdir: /builds/slave/test-pgo/build/application 10:00:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:00:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:00:39 INFO - Reading from file tmpfile_stdout 10:00:39 INFO - Output received: 10:00:39 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:00:39 INFO - Running post-action listener: _resource_record_post_action 10:00:39 INFO - ##### 10:00:39 INFO - ##### Running run-tests step. 10:00:39 INFO - ##### 10:00:39 INFO - Running pre-action listener: _resource_record_pre_action 10:00:39 INFO - Running main action method: run_tests 10:00:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:00:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:00:42 INFO - Python 2.7.3 10:00:42 INFO - Return code: 0 10:00:42 INFO - grabbing minidump binary from tooltool 10:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:42 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-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2816350>, '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 0x2817250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28176e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:00:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 10:00:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:00:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:42 INFO - Return code: 0 10:00:42 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:00:42 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:00:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:00:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:00:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:00:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:00:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:00:43 INFO - Return code: 0 10:00:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:00:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost 10:00:43 INFO - Using env: {'DISPLAY': ':0', 10:00:43 INFO - 'HOME': '/home/cltbld', 10:00:43 INFO - 'LANG': 'en_US.UTF-8', 10:00:43 INFO - 'LANGUAGE': 'en_US:en', 10:00:43 INFO - 'LOGNAME': 'cltbld', 10:00:43 INFO - 'MAIL': '/var/mail/cltbld', 10:00:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:00:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:43 INFO - 'MOZ_NO_REMOTE': '1', 10:00:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:43 INFO - 'NO_EM_RESTART': '1', 10:00:43 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:43 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:43 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:00:43 INFO - 'SHELL': '/bin/bash', 10:00:43 INFO - 'SHLVL': '1', 10:00:43 INFO - 'TERM': 'linux', 10:00:43 INFO - 'TMOUT': '86400', 10:00:43 INFO - 'USER': 'cltbld', 10:00:43 INFO - 'XDG_SESSION_COOKIE': '256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533', 10:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:43 INFO - '_': '/tools/buildbot/bin/python'} 10:00:43 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost'] with output_timeout 3600 10:00:49 INFO - mozversion INFO | application_buildid: 20151217072309 10:00:52 INFO - mozversion INFO | application_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 10:00:52 INFO - mozversion INFO | application_display_name: Nightly 10:00:52 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:52 INFO - mozversion INFO | application_name: Firefox 10:00:52 INFO - mozversion INFO | application_remotingname: firefox 10:00:52 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 10:00:52 INFO - mozversion INFO | application_vendor: Mozilla 10:00:52 INFO - mozversion INFO | application_version: 46.0a1 10:00:52 INFO - mozversion INFO | platform_buildid: 20151217072309 10:00:52 INFO - mozversion INFO | platform_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 10:00:52 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 10:00:52 INFO - mozversion INFO | platform_version: 46.0a1 10:00:52 INFO - 2015-12-17 10:00:49,605 DEBUG : using testdate: 1450375249 10:00:52 INFO - 2015-12-17 10:00:49,605 DEBUG : actual date: 1450375249 10:00:52 INFO - 2015-12-17 10:00:49,703 INFO : starting webserver on 'localhost:57020' 10:00:52 INFO - 2015-12-17 10:00:49,704 INFO : Starting test suite talos-linux64-ix-013 10:00:52 INFO - 2015-12-17 10:00:49,704 INFO : Starting test dromaeo_css 10:00:52 INFO - 2015-12-17 10:00:49,704 DEBUG : operating with platform_type : linux_ 10:00:52 INFO - 2015-12-17 10:00:49,704 INFO : Initialising browser for dromaeo_css test... 10:00:52 INFO - 2015-12-17 10:00:49,713 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLCbbVD/profile http://localhost:57020/getInfo.html 10:01:25 INFO - 2015-12-17 10:01:25,221 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:01:25 INFO - 2015-12-17 10:01:25,222 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:01:25 INFO - 2015-12-17 10:01:25,222 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:01:25 INFO - 2015-12-17 10:01:25,222 DEBUG : BROWSER_OUTPUT: __metrics 10:01:25 INFO - 2015-12-17 10:01:25,633 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:01:25 INFO - 2015-12-17 10:01:25,700 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:01:32 INFO - 2015-12-17 10:01:32,277 INFO : Browser initialized. 10:01:32 INFO - 2015-12-17 10:01:32,277 INFO : Running cycle 1/1 for dromaeo_css test... 10:01:32 INFO - 2015-12-17 10:01:32,278 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLCbbVD/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:01:44 INFO - 2015-12-17 10:01:44,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 149962752 10:01:44 INFO - 2015-12-17 10:01:44,093 DEBUG : BROWSER_OUTPUT: 10:02:35 INFO - 2015-12-17 10:02:35,663 DEBUG : BROWSER_OUTPUT: 1450375355656 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:04:47 INFO - 2015-12-17 10:04:47,024 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57020/tests/dromaeo/cssquery-ext.html) 10:04:48 INFO - 2015-12-17 10:04:48,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 282439680 10:04:48 INFO - 2015-12-17 10:04:48,331 DEBUG : BROWSER_OUTPUT: 10:07:50 INFO - 2015-12-17 10:07:50,766 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/dromaeo/cssquery-ext.html (next: http://localhost:57020/tests/dromaeo/cssquery-jquery.html) 10:07:51 INFO - 2015-12-17 10:07:51,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 231739392 10:07:51 INFO - 2015-12-17 10:07:51,331 DEBUG : BROWSER_OUTPUT: 10:10:53 INFO - 2015-12-17 10:10:53,268 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57020/tests/dromaeo/cssquery-mootools.html) 10:10:53 INFO - 2015-12-17 10:10:53,855 DEBUG : BROWSER_OUTPUT: RSS: Main: 226856960 10:10:53 INFO - 2015-12-17 10:10:53,855 DEBUG : BROWSER_OUTPUT: 10:13:55 INFO - 2015-12-17 10:13:55,659 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57020/tests/dromaeo/cssquery-prototype.html) 10:13:56 INFO - 2015-12-17 10:13:56,232 DEBUG : BROWSER_OUTPUT: RSS: Main: 211169280 10:13:56 INFO - 2015-12-17 10:13:56,232 DEBUG : BROWSER_OUTPUT: 10:16:58 INFO - 2015-12-17 10:16:58,674 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57020/tests/dromaeo/cssquery-yui.html) 10:16:59 INFO - 2015-12-17 10:16:59,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 213590016 10:16:59 INFO - 2015-12-17 10:16:59,263 DEBUG : BROWSER_OUTPUT: 10:20:01 INFO - 2015-12-17 10:20:01,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/dromaeo/cssquery-yui.html (next: http://localhost:57020/tests/dromaeo/cssquery-dojo.html) 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 254345216 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:20:02 INFO - 2015-12-17 10:20:02,195 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3088.9073327800165;3102.4570700127474;3141.978856871517;3150.1022208670533;3185.4106990206815;5043.848684539461;5153.4846515348445;5181.455947125554;5189.1437912744395;5207.765650545725;5174.4825517448235;5199.6360254782185;5242.475752424755;5246.265522826808;5262.9473705262935;4627.282771170457;4794.496577859327;4806.7115973041555;4822.493638167985;4864.878378040542;4742.19382704941;4815.735134567597;4832.613390928735;4885.340479035338;4900.950990490103;4734.076855013273;4799.208130658437;4830.299606557065;4844.346013288232;4872.3422337984275;4795.2807078938085;4803.807847686088;4818.975905120487;4819.036192761461;4837.637177211724;4594.471635761864;4698.389209402756;4769.165396055711;4776.188048031843;4825.469198388175;4906.067847109038;4984.925226121611;4987.925181122286;4999.575036121934;5029.069622119883;5695.572832037614;5698.1452782083015;5700.515456186229;5701.515371193454;5705.4294570543025;5443.1835224716215;5580.748866301026;5729.197912292282;5733.111367738007;5737.942620573783;5160.380754309507;5228.08508511009;5232.686038837682;5247.370315562119;5272.156454967187;5648.322201335866;5649.971750141223;5652.547796176296;5655.5475561954945;5662.858428539237;5439.945600543944;5487.478689524489;5488.643238189505;5493.148561972901;5539.473749993744;5521.779128834802;5609.046462101453;5618.101103823368;5620.1288800236025;5671.177679236487;5497.587680923947;5514.889702205934;5539.19681646159;5547.916781248285;5604.271444712162;5488.670679759227;5561;5566.805161819317;5617.9438205618235;5646.378898321182;4292.849750258789;4343.869683909425;4359.760213188305;4360.345948107746;4395.120975804852;3838.558565764917;3865.690744740414;3869.071422858494;3914.8825535233987;3930.8624198153498;5082.364704411949;5181.870453238624;5250.921236181461;5273.789048438096;5299.788008479618;5560.7219639017885;5593.105103183471;5619.438056194348;5637.295338082739;5692.9146062809095;5343.465653434625;5360.088784906575;5360.2227676987395;5367.865803354869;5368.812091576776;4778.402699662543;4876.780544875489;4942;4942.851714448572;4986.650934434628;5570.470805273492;5591.944080559224;5634.295713035953;5638.802641907513;5688.82933511987;5636.830895073154;5689.857753556194;5692.231548740867;5694;5695.658260504383;5522.061249587499;5627.634203776742;5717.656940583578;5718.25662663851;5771.740271687868;5396.9730151348995;5519.31008623922;5523.337199536081;5549.611527193058;5567.832965011056;5485.50630443262;5518.613697041169;5520.806771762969;5529.364123125892;5549.5837812163445;5239.397469291081;5369.19462080691;5383.81156659515;5387.326584176978;5412.458754124714;5447.700376479332;5498.615096943176;5512.7519261634125;5514.724263786875;5565.526930210977;5387.488188622075;5388.991658231806;5390.164524498552;5390.946090539202;5404.513593776579;5359.571234301325;5415.864603384947;5424.105022671373;5486.177073439016;5548.889022219614;5296.285001524745;5346.144616861284;5367.221752845893;5371.704556249444;5377.489138531833 10:20:02 INFO - 2015-12-17 10:20:02,196 DEBUG : BROWSER_OUTPUT: |1;ext.html;549.0885130683067;589.811260396673;600.2916558461015;600.6936462404173;610.0757352610796;30134.547981780262;37700.622993770055;38483.23033539327;38497;38585.80707096468;42364.364534532004;42381.36427953583;42638;42770.35844462336;42897.35653965193;7516.586587737672;7625.542467451943;8579.399442039075;8745.95627021864;8752.256058235058;10440.791184176313;10675.78648427031;10682.145428365711;10682.572697092108;10683.412412317319;10274.178065754722;10312.484375781218;10347.844782328271;10362.844557331646;10378.636747713832;7849.11621210911;8038.557879316635;10241.385516869012;10322.948385258102;10328.948355258213;10332.431716255604;10576.629817956411;10582.629607963761;10585.364878107339;10585.364878107339;38191.236175276616;38505.422418663744;38886.61113388858;38908.80545597282;39020.804895975336;3222.7744057915957;3225.3388055448813;3258.104021394112;3271.0768205928825;3308.3052558962645;29774.255643608867;30004.69995300041;30113.69886301131;30248.69751302503;30847;3530.770499917497;3582.5880023797336;3618.330608837347;3639.090227443139;3756.042209236627;264726;265131;265437.9999999981;265832;265842;53498;54818;54853.45146548563;55123.72438137783;56317;54902;55160;55183.44816551863;55192;55249.72375138179;15121.319540620698;15130.352188367211;15215.467458638894;15220.771688424682;15340.386384544494;9303;9368.578413971387;12265.448054837554;12370.75258494825;12381.380930953417;18718.344857929907;19606.90697859472;19696.803031969495;19759;19814.306499272458;7137.179224389261;7442.292982166686;7452.292032256927;7487.625618719042;7502.774916752506;924.9167574918155;1014.300132908291;1021.5658345203259;1033.534909290822;1047.9677517645114;26588;27187.1843844685;29014.854925725238;29626.259343516584;29733;1033.6020632056561;1047.115187666436;1052.1425038593522;1107.9861926337471;1123.1969142063465;13188.406521706354;13584.320783960762;13615.18308901469;13677;14097.85902140986;297151;301257;301410;301508;301706;49919.25121123186;50119.24821127686;50158;50180.24729629058;50211.49788502141;1293.8512071111754;1307.0981023094225;1312.1339915655622;1312.5996571045923;1320.4850108457708;25735.871320644026;26525.86737066302;26590.7340926596;26733.866330669;26740;42638.78680606577;42784.14942655176;42841;42900.78549607232;42910.141797163255;279919;281370;282267;282518;283446;26891;27417.90328386922;29302.853485732434;30346.54480182755;30393.392132157674;36649;37373.252534949694;38573.03567410725;38636.420453692655;39581.40627890526;33247.66752332543;33452.330953381286;33456.1635959103;33595.32809343848;34151.316973659894;28745;33163.02004608809;33690;34018.3196336067;34132.317353653285;29162.854185728935;31367;31516.842415787774;31541.684583153874;31566.36867262688;26617.632442103015;29164;30141;32327.51508727324;32333;23850;24085.397865053514;24250.75749242485;24550.140745073837;24579.754202457745 10:20:02 INFO - 2015-12-17 10:20:02,196 DEBUG : BROWSER_OUTPUT: |2;jquery.html;362.18146987807546;363.71811845819497;368.10733970122453;368.37744212281257;372.5380528145101;14482.782758258634;14903.40386384546;16344.509664710064;16884.662306753857;17514.21186046626;13152;14920;15077.6984460311;16401.91799041006;16411.343546258136;5470.124780035195;5620.718964051781;5651.095824668054;5660.886782264352;5669.914951275714;3744.1388480649466;3858.5369755629363;5272.1300985337375;5317.9468205318035;5606.21512988182;5428;5457.181422786574;5480.068388373986;5487.6981766002855;5501.9174712379345;5392.703401312926;5439.5376393006645;5782.2483077199695;6004.489618382443;6012.488938440194;5305.734713264322;5307.65500242483;5315.415304316522;5322.387925388591;5322.387925388591;11832.585859494962;20541.8972905136;20988.895055524776;21046.368608941757;21050.47394312677;1961.901904904749;2014.5567975045467;2136.5192831612917;2158.0720290275176;2171.4354267890303;9240.630374785;9320.25437964968;9410.294227932933;9518.238540916711;9639.759006024835;1822.4168266154836;1847.1503108570073;1860.153630098302;1878.7557617509635;1883.9811601883944;52970.20544691794;58018;59845.7007714963;61367;62068;10007.54966026516;10255.846162307422;10342.482875856178;10401.479926003822;10430.895691042992;13411.798823017663;13427.798583021262;13451.730965380833;13457.327133643279;13463.192208467328;7403.51877127985;7466.328030477283;7514.586697731677;7733.613319334011;7763.573003484749;7399.926000740032;7457.403407727369;7493.700251989969;7507.737229196952;7512.39900807934;4429.136318417942;4486;4493.258612328929;4511.661625378109;4545.113702827917;1761.11944027986;1793.2157503284316;1801.9909900450414;1803.6212395396851;1807.204829874851;838.2497664590157;914;967.6951760195516;985.3742873275521;1005.8541511480794;15520.5343839685;15624.609384765245;16187.595310117342;16490;16625.584360390843;794.7417089445953;827.3339961331186;896.744427838076;984.8178087053918;985.9211263098935;5896.086106653475;5899.675517846474;6044.274687037496;6061.242344706911;8738.86891696625;385426;390382;395485;399537;400674;79328.60335698284;80130.59934700289;80857.59571202107;81793;82790.17209827945;499.33338992444766;499.86753510319795;504.89397226582093;505.7976809276319;507.3227241632462;9678.370905891095;9944.900550994398;10163.84754228702;10164.33931794431;10188.789653513863;35965;36011;36344.09139771421;37090.44364334591;37404.81297593604;46375.304370435144;48534.27198592095;48692.75653621709;49095.26357104575;52885;9162.725118246197;9317.301202409712;9408.20030297405;9531.380460270339;9648.75878103053;11153.944230278797;11305.773884522428;11360.318380896842;11371;13564;14438.566842994727;14713.632159195677;14801.703965920406;14885.776713349525;15041.774373384189;14005.089669171472;14114.435422582981;14347.210903399995;14571.271436427924;14744;11756.529738810352;11865.169438139592;12412.62762117138;14424.423023079378;15302.311395986984;9124.726258212264;9155.908440915506;9183.586738596645;9489.762755931157;9659.758506037406;3767.962320376761;8969.237614802814;14638.926805365905;14987.775183372476;15008.47470338533 10:20:02 INFO - 2015-12-17 10:20:02,196 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1341.1282666266932;1666.1585899120942;1721.449136276392;1736.9044702541364;1760.3398725477946;6683.264840867507;7299.5255308404985;7438.181800001996;7438.442116841232;7459.104907411118;7387.4459415543915;7537.886931696029;7541.170471248172;7543.132539757929;7554.395648348132;1915.9712604310946;1951.668216403215;1953.4725624081489;2170.2729585588845;2206.2939859244966;2142.9035693393757;2181.9563608727817;2182.028997096285;2182.432567532445;2183.4977955070344;2011.7384739983854;2024.5849600831793;2174.510735084601;2180.8473406861526;2182.9849120159124;2150.7956744109283;2163.7187165668443;2189.5949249388837;2190.507135894427;2191.3206905859233;2186.4752459409788;2216.5788500185076;2219.411855858199;2223.455253462912;2223.566404551113;7458.067741532309;7555.320021198118;7572.167061623217;7573.35626471745;7580.128285247212;4434.578715022068;4503.909921801545;4531.818727250906;4538.614217791492;4545.636349092098;2878.208492664513;2880.4815133276;2936.265933516621;2948.2629342664336;2965.0660042086674;891.6032365597247;897.7486303834871;904.3036861616584;926.3191554207652;953.2564599612244;10367.22245831558;10385.42880141592;10388.896111038945;10396.844047339297;10399.272050956437;3991.521017477863;4000.6599439047427;4045.6763458923215;4052.2705912935953;4062.9187416252103;8870.822583548294;8876.733697989072;8887;8887.688930887389;8888.244499217639;4409.338599210144;4415.227335216388;4416.094700586372;4419.64642828573;4425.269830477935;4001.7198796084576;4029.9194016119513;4031.496062992126;4031.91936161281;4038.5759495252573;3084.5373194021076;3117.1583672408774;3396.100033491127;3516.4197907345515;3581.9104522387147;1128.418864284894;1132.841402203692;1135;1136.710138914588;1156.0231604294358;987.1757082835769;1006.3207335048813;1010.0303708439833;1100.510272928539;1269.2701696524534;6989.33601307875;7059.788206353817;7067.081279433641;7074.115735533059;7079;1075.5858994287255;1081.6592773276393;1085.9674209773718;1092.4592326798286;1136.9772604547863;5539.723013849291;5622.8313150605545;5627.971860140755;5629.324481062217;5639.43605639441;8643.783905402415;8933.240674542736;8953.238974687225;8971.102889710977;8976.281897448189;8788.384813063025;8842.734717958343;8862.379633425599;8866.556672166365;8867.822643547093;6896.862062758817;6981.965090174516;6993.89509157363;7006.579605223805;7057.223705392403;6798.592084474947;6807.115075040213;6820.283870193558;6821.93178068213;6839.555428897304;7839.960800195962;7924.9207507926485;7973.920260797549;8023.6389362479995;8035.638396272065;8047.678092876454;8195.057568379712;8298.83402331938;8329.167083291622;8338.708145214889;6488.70800813974;6528.967355163194;6565.113709649328;6846.520743548103;6850.212225593921;6095.634261944298;6305.4009869062365;6709.094272273376;6835.384815366641;7033.050538177281;6521.413072823469;6540.182477190351;6592.637404942774;6689.531732778659;6761.391474767294;6533.444657204191;6556.98366753154;6599.274079851309;6663.83340416474;6679.966600166968;6549.7707580236365;6583.670816459062;6588.967055164693;6598.175228096488;6603.570767900071;6116.602420842631;6123.173371594956;6124.908126378019;6126.938730612816;6130.969345153246;6079.908801367895;6338.968305158444;6361;6365.045243213555;6371.553991220755 10:20:02 INFO - 2015-12-17 10:20:02,196 DEBUG : BROWSER_OUTPUT: |4;prototype.html;321.7104605854731;330.44485264755207;336.93864327368794;338.8661478715909;343.35620711166564;5954.106883967407;5958.970205148974;5971.820845374635;5996.580239383245;6000.7299671514775;5654.293213348332;5762.4525670061275;5797.739101740422;5897.351291357958;5921.171036054956;4541.386912766768;4558.635309175258;4581.931271030937;4582.9770851145695;4590.173768721629;4403.185410699031;4583.220852455091;4583.9541604583865;4584.656150788687;4588.128255631436;4430.4240448741775;4467.776611169445;4533;4559.658025648073;4575.771211439432;4542;4557.612602928755;4571.3600095986585;4576.336431217472;4577.816887324503;4014.2172276406113;4019.618136277049;4021.658159056484;4024.3359845625437;4028.3554631258858;5536.667799932017;5656.434356564352;5664.773409063632;5666.716664166775;5671.291088613923;5280.49835265653;5296.496832800878;5298.814541491068;5304.840854774363;5305.814296499604;6234.625922444668;6288.937110628926;6289.276733175698;6292.276388215322;6293.709789493143;4418.204723149832;4422.248217802981;4423.51341352451;4423.557644235583;4430.889227769299;6328.955722305814;6495.772647957346;6519.706613202417;6527.967360163168;6536.05227242052;4517.977410112929;4523.7285762854335;4525.547445255514;4529.796159172845;4541.318802179633;4578.198815207325;4579.610733087658;4586.151561961048;4587.655925805578;4588.128255631419;5276.050310944067;5276.947230527646;5281.577473802164;5282.392524859613;5284.524392804666;6174.38256174379;6296.622202667854;6312.242530896322;6321.209848768904;6331.556791024584;1614.0638429710975;1636.5090472858094;1636.6235765773702;1637.1568642149302;1638.221844623818;1097.533548241994;1098.5880294889416;1101.1741194104422;1110.7334239782394;1135.330155208431;6083.239595050619;6089.604175728564;6094.786682466181;6095.603785753912;6096.634201947897;6537.280899101095;6543.509236807258;6551.868962620721;6553.246376666744;6561.967190164019;5926;5991.730372133265;6082.087686847009;6101.542384321194;6113.84715382119;3525.8765943191866;3539.0267676388685;3549.290141971616;3582.30145121704;3590.9461358079648;6727.529072964943;6728.3271672832325;6731.427828634523;6733.595984240961;6738.663066846736;6626.9337306627285;6757.121574195421;6762.864742705118;6775.22084960226;6778.237658208901;6733.259341472434;6733.663316834139;6733.93266067333;6736.966315168393;6740.865182696319;6592.736290548324;6604.339566043357;6606.042123892104;6610.206775187008;6611.33886611335;6345.968270158804;6521.510886683613;6534.4772418205475;6540.771073012422;6544.83637909037;6721.764738234138;6724.630145342053;6725.865482690221;6726.966365168143;6729.629870357177;4502.234620114654;4529.433820772403;4530.25250833622;4535.31970204472;4538.568835960578;6373.458256048288;6518.902216466851;6526.118973938457;6534.1178940844275;6547;6347.90478142819;6349.6507692077375;6356.904646430215;6358.491320694426;6369.108324834619;6324.747010119544;6334.049892516159;6338;6346.174997250328;6349.238091429058;6140.416660417253;6228.4705800007505;6290.748370065146;6305.96847015762;6327.430531252209;6425.1968503937005;6428.260750013808;6428.839279017875;6436.806895793134;6449.774257900951;6311.495080393649;6316.526260530571;6316.62100273985;6318.08387783778;6318.399752023551 10:20:02 INFO - 2015-12-17 10:20:02,197 DEBUG : BROWSER_OUTPUT: |5;yui.html;759.1307952394509;769.6921231507397;812.6180695073314;845.5983407881255;851.8594431918734;11417.714557136065;11651.592194273202;11653.766924661502;11675.299482031083;11679.532818687243;11822.645320640373;11824.29054256743;13169.736605267914;13434.261115638636;13453.394597243123;801.6432687454085;815.2784785464877;815.3640160674684;815.6818840652151;816.0574536410426;678.4979115454578;686.8729285082251;794.2494342846013;816.1145157504086;819.7171975668415;852.6930305090162;853.8975322961222;858.4849090545686;858.5149390594325;859.3168431097262;710.3571268002466;852.0499642898155;855.315747402082;856.1395797223781;856.6787454704486;527.4066674990636;616.6577549460048;625.9906101408483;626.9122322874802;627.3976982097192;11988.220765650205;12222.511099556006;12279.631611051684;12288.447019884037;12293.69265768354;11250;11283.774324513546;12056.457459414347;12340;12523.874761252362;8722.607482663296;10117.392956422638;10381.905452810834;10659.147268218601;10907.618233361874;3638.708903287757;3680.2271522980204;3706.814659267026;3770.830312635938;3775.5846856845724;49084;49460.50539494595;49499;49568;49610;8529.360297977677;9079.773005674779;9504.42973421597;9691.12779849817;10349.94825025885;16350.427735029454;16450.506484805475;16478.588035299214;16492.25784839685;16556.6688666226;7024.929750702529;7241.5293005954445;7286.834106542928;7355.264473552709;7462;3803.733738638324;3841.654251117413;3867.7292589518465;3900.5124359455067;3910.0029492479234;3028.303490197267;3075.7693173012112;3081.6302043754895;3081.830499322514;3082.799618024821;548.8243761996123;558.4443478738681;561.50026476436;568.139269007454;575.6977586766897;573.0973716396692;576.5848589015902;598.4105655928906;621.487272999777;686.8935315026179;8205.261526462647;8207.589620518951;8210.917890821134;8258.256756891788;8450.366222533212;533.7518054104783;558.3132746721474;596.0879853823657;610.4993904997859;619.0961196652847;3871.554771201348;3973.2848087343978;3974.5429275633674;3979.6617287530307;4088.100617864065;61077.38922610806;61248;61778;61885.07172392418;61924;55437.1684424734;56757.14864277039;56771.432285676616;56820;56915.430845691015;49444.75277623661;49660.25509617358;50217.24674129818;50229.74885125624;51164;7601.353884919844;7646.770596882103;7688.269614386624;7741.535507869546;7889.13219545838;35746.28507429785;35839;36088.27823443569;36167.27665446624;37141.62858371382;44900.77549612231;44933.550664492934;45951.77024114858;46771.532284676716;47584.286235707186;9274.768130796514;9301.162895339181;9308.767280817763;9374.531273436438;9728.367656102331;10088.848667270144;10484.26610137283;10666.679999600025;10695.358278503314;10751.677449676523;9098.772530686785;9152.725418237464;9331.72004839856;9450.669226577313;9607.663731769355;9610.807783844424;9698.9030109698;9706.85439718419;10030.64892728751;10158.13655839262;8828.382013259266;9955.601775928882;10145.137663298703;10151.64469243573;10655.30740501865;8833.160849719266;9154.176124148858;9234.907650923406;9277.443353398818;9411.341206115507;9173.220276276592;9228.769280768034;9403.858942115738;9429.434233945985;9550.90449095528 10:20:02 INFO - 2015-12-17 10:20:02,197 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:20:02 INFO - 2015-12-17 10:20:02,197 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:20:02 INFO - 2015-12-17 10:20:02,197 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1119 10:20:02 INFO - 2015-12-17 10:20:02,197 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:20:02 INFO - 2015-12-17 10:20:02,197 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376402191__endTimestamp 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5191.68 Median:5377.49 stddev:557.91 (10.4%) stddev-sans-first:534.67 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: Values: 3088.9 3102.5 3142.0 3150.1 3185.4 5043.8 5153.5 5181.5 5189.1 5207.8 5174.5 5199.6 5242.5 5246.3 5262.9 4627.3 4794.5 4806.7 4822.5 4864.9 4742.2 4815.7 4832.6 4885.3 4901.0 4734.1 4799.2 4830.3 4844.3 4872.3 4795.3 4803.8 4819.0 4819.0 4837.6 4594.5 4698.4 4769.2 4776.2 4825.5 4906.1 4984.9 4987.9 4999.6 5029.1 5695.6 5698.1 5700.5 5701.5 5705.4 5443.2 5580.7 5729.2 5733.1 5737.9 5160.4 5228.1 5232.7 5247.4 5272.2 5648.3 5650.0 5652.5 5655.5 5662.9 5439.9 5487.5 5488.6 5493.1 5539.5 5521.8 5609.0 5618.1 5620.1 5671.2 5497.6 5514.9 5539.2 5547.9 5604.3 5488.7 5561.0 5566.8 5617.9 5646.4 4292.8 4343.9 4359.8 4360.3 4395.1 3838.6 3865.7 3869.1 3914.9 3930.9 5082.4 5181.9 5250.9 5273.8 5299.8 5560.7 5593.1 5619.4 5637.3 5692.9 5343.5 5360.1 5360.2 5367.9 5368.8 4778.4 4876.8 4942.0 4942.9 4986.7 5570.5 5591.9 5634.3 5638.8 5688.8 5636.8 5689.9 5692.2 5694.0 5695.7 5522.1 5627.6 5717.7 5718.3 5771.7 5397.0 5519.3 5523.3 5549.6 5567.8 5485.5 5518.6 5520.8 5529.4 5549.6 5239.4 5369.2 5383.8 5387.3 5412.5 5447.7 5498.6 5512.8 5514.7 5565.5 5387.5 5389.0 5390.2 5390.9 5404.5 5359.6 5415.9 5424.1 5486.2 5548.9 5296.3 5346.1 5367.2 5371.7 5377.5 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,198 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44433.88 Median:301607.00 stddev:73831.12 (24.5%) stddev-sans-first:73964.71 10:20:02 INFO - 2015-12-17 10:20:02,199 DEBUG : BROWSER_OUTPUT: Values: 549.1 589.8 600.3 600.7 610.1 30134.5 37700.6 38483.2 38497.0 38585.8 42364.4 42381.4 42638.0 42770.4 42897.4 7516.6 7625.5 8579.4 8746.0 8752.3 10440.8 10675.8 10682.1 10682.6 10683.4 10274.2 10312.5 10347.8 10362.8 10378.6 7849.1 8038.6 10241.4 10322.9 10328.9 10332.4 10576.6 10582.6 10585.4 10585.4 38191.2 38505.4 38886.6 38908.8 39020.8 3222.8 3225.3 3258.1 3271.1 3308.3 29774.3 30004.7 30113.7 30248.7 30847.0 3530.8 3582.6 3618.3 3639.1 3756.0 264726.0 265131.0 265438.0 265832.0 265842.0 53498.0 54818.0 54853.5 55123.7 56317.0 54902.0 55160.0 55183.4 55192.0 55249.7 15121.3 15130.4 15215.5 15220.8 15340.4 9303.0 9368.6 12265.4 12370.8 12381.4 18718.3 19606.9 19696.8 19759.0 19814.3 7137.2 7442.3 7452.3 7487.6 7502.8 924.9 1014.3 1021.6 1033.5 1048.0 26588.0 27187.2 29014.9 29626.3 29733.0 1033.6 1047.1 1052.1 1108.0 1123.2 13188.4 13584.3 13615.2 13677.0 14097.9 297151.0 301257.0 301410.0 301508.0 301706.0 49919.3 50119.2 50158.0 50180.2 50211.5 1293.9 1307.1 1312.1 1312.6 1320.5 25735.9 26525.9 26590.7 26733.9 26740.0 42638.8 42784.1 42841.0 42900.8 42910.1 279919.0 281370.0 282267.0 282518.0 283446.0 26891.0 27417.9 29302.9 30346.5 30393.4 36649.0 37373.3 38573.0 38636.4 39581.4 33247.7 33452.3 33456.2 33595.3 34151.3 28745.0 33163.0 33690.0 34018.3 34132.3 29162.9 31367.0 31516.8 31541.7 31566.4 26617.6 29164.0 30141.0 32327.5 32333.0 23850.0 24085.4 24250.8 24550.1 24579.8 10:20:02 INFO - 2015-12-17 10:20:02,199 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,199 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24307.06 Median:4457.57 stddev:64951.41 (1457.1%) stddev-sans-first:65108.74 10:20:02 INFO - 2015-12-17 10:20:02,200 DEBUG : BROWSER_OUTPUT: Values: 362.2 363.7 368.1 368.4 372.5 14482.8 14903.4 16344.5 16884.7 17514.2 13152.0 14920.0 15077.7 16401.9 16411.3 5470.1 5620.7 5651.1 5660.9 5669.9 3744.1 3858.5 5272.1 5317.9 5606.2 5428.0 5457.2 5480.1 5487.7 5501.9 5392.7 5439.5 5782.2 6004.5 6012.5 5305.7 5307.7 5315.4 5322.4 5322.4 11832.6 20541.9 20988.9 21046.4 21050.5 1961.9 2014.6 2136.5 2158.1 2171.4 9240.6 9320.3 9410.3 9518.2 9639.8 1822.4 1847.2 1860.2 1878.8 1884.0 52970.2 58018.0 59845.7 61367.0 62068.0 10007.5 10255.8 10342.5 10401.5 10430.9 13411.8 13427.8 13451.7 13457.3 13463.2 7403.5 7466.3 7514.6 7733.6 7763.6 7399.9 7457.4 7493.7 7507.7 7512.4 4429.1 4486.0 4493.3 4511.7 4545.1 1761.1 1793.2 1802.0 1803.6 1807.2 838.2 914.0 967.7 985.4 1005.9 15520.5 15624.6 16187.6 16490.0 16625.6 794.7 827.3 896.7 984.8 985.9 5896.1 5899.7 6044.3 6061.2 8738.9 385426.0 390382.0 395485.0 399537.0 400674.0 79328.6 80130.6 80857.6 81793.0 82790.2 499.3 499.9 504.9 505.8 507.3 9678.4 9944.9 10163.8 10164.3 10188.8 35965.0 36011.0 36344.1 37090.4 37404.8 46375.3 48534.3 48692.8 49095.3 52885.0 9162.7 9317.3 9408.2 9531.4 9648.8 11153.9 11305.8 11360.3 11371.0 13564.0 14438.6 14713.6 14801.7 14885.8 15041.8 14005.1 14114.4 14347.2 14571.3 14744.0 11756.5 11865.2 12412.6 14424.4 15302.3 9124.7 9155.9 9183.6 9489.8 9659.8 3768.0 8969.2 14638.9 14987.8 15008.5 10:20:02 INFO - 2015-12-17 10:20:02,200 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,200 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5203.67 Median:6218.19 stddev:2704.52 (43.5%) stddev-sans-first:2696.52 10:20:02 INFO - 2015-12-17 10:20:02,200 DEBUG : BROWSER_OUTPUT: Values: 1341.1 1666.2 1721.4 1736.9 1760.3 6683.3 7299.5 7438.2 7438.4 7459.1 7387.4 7537.9 7541.2 7543.1 7554.4 1916.0 1951.7 1953.5 2170.3 2206.3 2142.9 2182.0 2182.0 2182.4 2183.5 2011.7 2024.6 2174.5 2180.8 2183.0 2150.8 2163.7 2189.6 2190.5 2191.3 2186.5 2216.6 2219.4 2223.5 2223.6 7458.1 7555.3 7572.2 7573.4 7580.1 4434.6 4503.9 4531.8 4538.6 4545.6 2878.2 2880.5 2936.3 2948.3 2965.1 891.6 897.7 904.3 926.3 953.3 10367.2 10385.4 10388.9 10396.8 10399.3 3991.5 4000.7 4045.7 4052.3 4062.9 8870.8 8876.7 8887.0 8887.7 8888.2 4409.3 4415.2 4416.1 4419.6 4425.3 4001.7 4029.9 4031.5 4031.9 4038.6 3084.5 3117.2 3396.1 3516.4 3581.9 1128.4 1132.8 1135.0 1136.7 1156.0 987.2 1006.3 1010.0 1100.5 1269.3 6989.3 7059.8 7067.1 7074.1 7079.0 1075.6 1081.7 1086.0 1092.5 1137.0 5539.7 5622.8 5628.0 5629.3 5639.4 8643.8 8933.2 8953.2 8971.1 8976.3 8788.4 8842.7 8862.4 8866.6 8867.8 6896.9 6982.0 6993.9 7006.6 7057.2 6798.6 6807.1 6820.3 6821.9 6839.6 7840.0 7924.9 7973.9 8023.6 8035.6 8047.7 8195.1 8298.8 8329.2 8338.7 6488.7 6529.0 6565.1 6846.5 6850.2 6095.6 6305.4 6709.1 6835.4 7033.1 6521.4 6540.2 6592.6 6689.5 6761.4 6533.4 6557.0 6599.3 6663.8 6680.0 6549.8 6583.7 6589.0 6598.2 6603.6 6116.6 6123.2 6124.9 6126.9 6131.0 6079.9 6339.0 6361.0 6365.0 6371.6 10:20:02 INFO - 2015-12-17 10:20:02,200 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,201 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5302.43 Median:5998.66 stddev:1587.83 (26.5%) stddev-sans-first:1547.65 10:20:02 INFO - 2015-12-17 10:20:02,201 DEBUG : BROWSER_OUTPUT: Values: 321.7 330.4 336.9 338.9 343.4 5954.1 5959.0 5971.8 5996.6 6000.7 5654.3 5762.5 5797.7 5897.4 5921.2 4541.4 4558.6 4581.9 4583.0 4590.2 4403.2 4583.2 4584.0 4584.7 4588.1 4430.4 4467.8 4533.0 4559.7 4575.8 4542.0 4557.6 4571.4 4576.3 4577.8 4014.2 4019.6 4021.7 4024.3 4028.4 5536.7 5656.4 5664.8 5666.7 5671.3 5280.5 5296.5 5298.8 5304.8 5305.8 6234.6 6288.9 6289.3 6292.3 6293.7 4418.2 4422.2 4423.5 4423.6 4430.9 6329.0 6495.8 6519.7 6528.0 6536.1 4518.0 4523.7 4525.5 4529.8 4541.3 4578.2 4579.6 4586.2 4587.7 4588.1 5276.1 5276.9 5281.6 5282.4 5284.5 6174.4 6296.6 6312.2 6321.2 6331.6 1614.1 1636.5 1636.6 1637.2 1638.2 1097.5 1098.6 1101.2 1110.7 1135.3 6083.2 6089.6 6094.8 6095.6 6096.6 6537.3 6543.5 6551.9 6553.2 6562.0 5926.0 5991.7 6082.1 6101.5 6113.8 3525.9 3539.0 3549.3 3582.3 3590.9 6727.5 6728.3 6731.4 6733.6 6738.7 6626.9 6757.1 6762.9 6775.2 6778.2 6733.3 6733.7 6733.9 6737.0 6740.9 6592.7 6604.3 6606.0 6610.2 6611.3 6346.0 6521.5 6534.5 6540.8 6544.8 6721.8 6724.6 6725.9 6727.0 6729.6 4502.2 4529.4 4530.3 4535.3 4538.6 6373.5 6518.9 6526.1 6534.1 6547.0 6347.9 6349.7 6356.9 6358.5 6369.1 6324.7 6334.0 6338.0 6346.2 6349.2 6140.4 6228.5 6290.7 6306.0 6327.4 6425.2 6428.3 6428.8 6436.8 6449.8 6311.5 6316.5 6316.6 6318.1 6318.4 10:20:02 INFO - 2015-12-17 10:20:02,201 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,202 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13787.43 Median:56867.72 stddev:17111.94 (30.1%) stddev-sans-first:17131.98 10:20:02 INFO - 2015-12-17 10:20:02,203 DEBUG : BROWSER_OUTPUT: Values: 759.1 769.7 812.6 845.6 851.9 11417.7 11651.6 11653.8 11675.3 11679.5 11822.6 11824.3 13169.7 13434.3 13453.4 801.6 815.3 815.4 815.7 816.1 678.5 686.9 794.2 816.1 819.7 852.7 853.9 858.5 858.5 859.3 710.4 852.0 855.3 856.1 856.7 527.4 616.7 626.0 626.9 627.4 11988.2 12222.5 12279.6 12288.4 12293.7 11250.0 11283.8 12056.5 12340.0 12523.9 8722.6 10117.4 10381.9 10659.1 10907.6 3638.7 3680.2 3706.8 3770.8 3775.6 49084.0 49460.5 49499.0 49568.0 49610.0 8529.4 9079.8 9504.4 9691.1 10349.9 16350.4 16450.5 16478.6 16492.3 16556.7 7024.9 7241.5 7286.8 7355.3 7462.0 3803.7 3841.7 3867.7 3900.5 3910.0 3028.3 3075.8 3081.6 3081.8 3082.8 548.8 558.4 561.5 568.1 575.7 573.1 576.6 598.4 621.5 686.9 8205.3 8207.6 8210.9 8258.3 8450.4 533.8 558.3 596.1 610.5 619.1 3871.6 3973.3 3974.5 3979.7 4088.1 61077.4 61248.0 61778.0 61885.1 61924.0 55437.2 56757.1 56771.4 56820.0 56915.4 49444.8 49660.3 50217.2 50229.7 51164.0 7601.4 7646.8 7688.3 7741.5 7889.1 35746.3 35839.0 36088.3 36167.3 37141.6 44900.8 44933.6 45951.8 46771.5 47584.3 9274.8 9301.2 9308.8 9374.5 9728.4 10088.8 10484.3 10666.7 10695.4 10751.7 9098.8 9152.7 9331.7 9450.7 9607.7 9610.8 9698.9 9706.9 10030.6 10158.1 8828.4 9955.6 10145.1 10151.6 10655.3 8833.2 9154.2 9234.9 9277.4 9411.3 9173.2 9228.8 9403.9 9429.4 9550.9 10:20:02 INFO - 2015-12-17 10:20:02,203 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:20:02 INFO - 2015-12-17 10:20:02,203 DEBUG : BROWSER_OUTPUT: 10:20:03 INFO - 2015-12-17 10:20:03,109 INFO : Browser exited with error code: 0 10:20:03 INFO - 2015-12-17 10:20:03,130 INFO : Completed test dromaeo_css (00:19:13) 10:20:03 INFO - 2015-12-17 10:20:03,130 INFO : Starting test kraken 10:20:03 INFO - 2015-12-17 10:20:03,130 DEBUG : operating with platform_type : linux_ 10:20:03 INFO - 2015-12-17 10:20:03,130 INFO : Initialising browser for kraken test... 10:20:03 INFO - 2015-12-17 10:20:03,136 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplBtuIZ/profile http://localhost:57020/getInfo.html 10:20:14 INFO - 2015-12-17 10:20:14,253 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:20:14 INFO - 2015-12-17 10:20:14,253 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:20:14 INFO - 2015-12-17 10:20:14,253 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:20:14 INFO - 2015-12-17 10:20:14,253 DEBUG : BROWSER_OUTPUT: __metrics 10:20:15 INFO - 2015-12-17 10:20:15,021 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:20:15 INFO - 2015-12-17 10:20:15,077 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:20:19 INFO - 2015-12-17 10:20:19,629 INFO : Browser initialized. 10:20:19 INFO - 2015-12-17 10:20:19,629 INFO : Running cycle 1/1 for kraken test... 10:20:19 INFO - 2015-12-17 10:20:19,629 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplBtuIZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:20:31 INFO - 2015-12-17 10:20:31,384 DEBUG : BROWSER_OUTPUT: RSS: Main: 150323200 10:20:31 INFO - 2015-12-17 10:20:31,384 DEBUG : BROWSER_OUTPUT: 10:20:33 INFO - 2015-12-17 10:20:33,842 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57020/tests/kraken/driver.html?testName=audio-beat-detection) 10:20:34 INFO - 2015-12-17 10:20:34,436 DEBUG : BROWSER_OUTPUT: RSS: Main: 185106432 10:20:34 INFO - 2015-12-17 10:20:34,436 DEBUG : BROWSER_OUTPUT: 10:20:37 INFO - 2015-12-17 10:20:37,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57020/tests/kraken/driver.html?testName=audio-dft) 10:20:37 INFO - 2015-12-17 10:20:37,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 175562752 10:20:37 INFO - 2015-12-17 10:20:37,635 DEBUG : BROWSER_OUTPUT: 10:20:40 INFO - 2015-12-17 10:20:40,511 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57020/tests/kraken/driver.html?testName=audio-fft) 10:20:41 INFO - 2015-12-17 10:20:41,072 DEBUG : BROWSER_OUTPUT: RSS: Main: 161546240 10:20:41 INFO - 2015-12-17 10:20:41,073 DEBUG : BROWSER_OUTPUT: 10:20:43 INFO - 2015-12-17 10:20:43,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57020/tests/kraken/driver.html?testName=audio-oscillator) 10:20:43 INFO - 2015-12-17 10:20:43,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 162172928 10:20:43 INFO - 2015-12-17 10:20:43,596 DEBUG : BROWSER_OUTPUT: 10:20:45 INFO - 2015-12-17 10:20:45,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57020/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:20:46 INFO - 2015-12-17 10:20:46,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 165060608 10:20:46 INFO - 2015-12-17 10:20:46,362 DEBUG : BROWSER_OUTPUT: 10:20:49 INFO - 2015-12-17 10:20:49,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57020/tests/kraken/driver.html?testName=imaging-darkroom) 10:20:49 INFO - 2015-12-17 10:20:49,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 164249600 10:20:49 INFO - 2015-12-17 10:20:49,813 DEBUG : BROWSER_OUTPUT: 10:20:53 INFO - 2015-12-17 10:20:53,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57020/tests/kraken/driver.html?testName=imaging-desaturate) 10:20:53 INFO - 2015-12-17 10:20:53,935 DEBUG : BROWSER_OUTPUT: RSS: Main: 163008512 10:20:53 INFO - 2015-12-17 10:20:53,935 DEBUG : BROWSER_OUTPUT: 10:20:56 INFO - 2015-12-17 10:20:56,656 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57020/tests/kraken/driver.html?testName=json-parse-financial) 10:20:57 INFO - 2015-12-17 10:20:57,225 DEBUG : BROWSER_OUTPUT: RSS: Main: 164003840 10:20:57 INFO - 2015-12-17 10:20:57,225 DEBUG : BROWSER_OUTPUT: 10:20:58 INFO - 2015-12-17 10:20:58,793 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57020/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:20:59 INFO - 2015-12-17 10:20:59,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 175226880 10:20:59 INFO - 2015-12-17 10:20:59,354 DEBUG : BROWSER_OUTPUT: 10:21:01 INFO - 2015-12-17 10:21:01,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:21:01 INFO - 2015-12-17 10:21:01,750 DEBUG : BROWSER_OUTPUT: RSS: Main: 173502464 10:21:01 INFO - 2015-12-17 10:21:01,751 DEBUG : BROWSER_OUTPUT: 10:21:03 INFO - 2015-12-17 10:21:03,802 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:21:04 INFO - 2015-12-17 10:21:04,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 184889344 10:21:04 INFO - 2015-12-17 10:21:04,375 DEBUG : BROWSER_OUTPUT: 10:21:06 INFO - 2015-12-17 10:21:06,789 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:21:07 INFO - 2015-12-17 10:21:07,374 DEBUG : BROWSER_OUTPUT: RSS: Main: 192090112 10:21:07 INFO - 2015-12-17 10:21:07,374 DEBUG : BROWSER_OUTPUT: 10:21:09 INFO - 2015-12-17 10:21:09,929 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:21:10 INFO - 2015-12-17 10:21:10,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 190177280 10:21:10 INFO - 2015-12-17 10:21:10,495 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57020/tests/kraken/driver.html?testName=ai-astar) 10:21:12 INFO - 2015-12-17 10:21:12,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 248549376 10:21:12 INFO - 2015-12-17 10:21:12,414 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,415 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:21:12 INFO - 2015-12-17 10:21:12,415 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:21:12 INFO - 2015-12-17 10:21:12,415 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:21:12 INFO - 2015-12-17 10:21:12,416 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:21:12 INFO - 2015-12-17 10:21:12,416 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;106;110;110;111;99;109;109;110 10:21:12 INFO - 2015-12-17 10:21:12,416 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;127;157;163;134;155;145;134;173;132 10:21:12 INFO - 2015-12-17 10:21:12,416 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;160;161;160;159;159;161;162;161 10:21:12 INFO - 2015-12-17 10:21:12,416 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;83;86;90;72;72;70;94;87;73 10:21:12 INFO - 2015-12-17 10:21:12,416 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;92;146;93;93;93;132;93;94;136 10:21:12 INFO - 2015-12-17 10:21:12,417 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;110;109;109;109 10:21:12 INFO - 2015-12-17 10:21:12,417 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;145;144;145;147;146;146;146;145;144 10:21:12 INFO - 2015-12-17 10:21:12,417 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;95;95;95;96;95;95 10:21:12 INFO - 2015-12-17 10:21:12,417 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;57;57;57;57;58;64;55;55 10:21:12 INFO - 2015-12-17 10:21:12,418 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;69;68;69;69;69;68;70 10:21:12 INFO - 2015-12-17 10:21:12,418 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;84;91;83;92;83;85;85;91 10:21:12 INFO - 2015-12-17 10:21:12,418 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;121;123;113;120;123;122;119;130;119 10:21:12 INFO - 2015-12-17 10:21:12,418 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;153;146;146;150;152;147;147;146;137 10:21:12 INFO - 2015-12-17 10:21:12,419 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;66;69;69;67;67;67;68;66;67 10:21:12 INFO - 2015-12-17 10:21:12,419 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:21:12 INFO - 2015-12-17 10:21:12,420 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:21:12 INFO - 2015-12-17 10:21:12,421 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,936 10:21:12 INFO - 2015-12-17 10:21:12,421 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:21:12 INFO - 2015-12-17 10:21:12,421 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376472414__endTimestamp 10:21:12 INFO - 2015-12-17 10:21:12,421 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,423 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:21:12 INFO - 2015-12-17 10:21:12,423 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:21:12 INFO - 2015-12-17 10:21:12,423 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,424 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.30 Median:110.00 stddev:3.53 (3.2%) stddev-sans-first:3.73 10:21:12 INFO - 2015-12-17 10:21:12,424 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 106.0 110.0 110.0 111.0 99.0 109.0 109.0 110.0 10:21:12 INFO - 2015-12-17 10:21:12,424 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,424 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.10 Median:153.00 stddev:15.20 (9.9%) stddev-sans-first:16.05 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: Values: 151.0 127.0 157.0 163.0 134.0 155.0 145.0 134.0 173.0 132.0 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:1.03 (0.6%) stddev-sans-first:1.00 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 160.0 161.0 160.0 159.0 159.0 161.0 162.0 161.0 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.80 Median:84.50 stddev:9.11 (10.8%) stddev-sans-first:9.09 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: Values: 71.0 83.0 86.0 90.0 72.0 72.0 70.0 94.0 87.0 73.0 10:21:12 INFO - 2015-12-17 10:21:12,425 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:93.00 stddev:22.01 (23.7%) stddev-sans-first:22.79 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: Values: 93.0 92.0 146.0 93.0 93.0 93.0 132.0 93.0 94.0 136.0 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,426 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: Values: 147.0 145.0 144.0 145.0 147.0 146.0 146.0 146.0 145.0 144.0 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.00 stddev:2.50 (4.4%) stddev-sans-first:2.65 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 57.0 57.0 57.0 57.0 58.0 64.0 55.0 55.0 10:21:12 INFO - 2015-12-17 10:21:12,427 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 69.0 68.0 69.0 69.0 69.0 68.0 70.0 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:85.00 stddev:3.70 (4.3%) stddev-sans-first:3.75 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 84.0 91.0 83.0 92.0 83.0 85.0 85.0 91.0 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:121.50 stddev:4.55 (3.7%) stddev-sans-first:4.51 10:21:12 INFO - 2015-12-17 10:21:12,428 DEBUG : BROWSER_OUTPUT: Values: 116.0 121.0 123.0 113.0 120.0 123.0 122.0 119.0 130.0 119.0 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.20 Median:147.00 stddev:5.25 (3.6%) stddev-sans-first:4.65 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: Values: 138.0 153.0 146.0 146.0 150.0 152.0 147.0 147.0 146.0 137.0 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.07 (1.6%) stddev-sans-first:1.12 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: Values: 68.0 66.0 69.0 69.0 67.0 67.0 67.0 68.0 66.0 67.0 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:21:12 INFO - 2015-12-17 10:21:12,429 DEBUG : BROWSER_OUTPUT: 10:21:14 INFO - 2015-12-17 10:21:14,329 INFO : Browser exited with error code: 0 10:21:14 INFO - 2015-12-17 10:21:14,383 INFO : Completed test kraken (00:01:11) 10:21:14 INFO - 2015-12-17 10:21:14,383 INFO : Starting test v8_7 10:21:14 INFO - 2015-12-17 10:21:14,383 DEBUG : operating with platform_type : linux_ 10:21:14 INFO - 2015-12-17 10:21:14,383 INFO : Initialising browser for v8_7 test... 10:21:14 INFO - 2015-12-17 10:21:14,389 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnL6QhN/profile http://localhost:57020/getInfo.html 10:21:26 INFO - 2015-12-17 10:21:26,805 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:21:26 INFO - 2015-12-17 10:21:26,805 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:21:26 INFO - 2015-12-17 10:21:26,805 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:21:26 INFO - 2015-12-17 10:21:26,805 DEBUG : BROWSER_OUTPUT: __metrics 10:21:27 INFO - 2015-12-17 10:21:27,386 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:21:27 INFO - 2015-12-17 10:21:27,434 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:21:32 INFO - 2015-12-17 10:21:32,212 INFO : Browser initialized. 10:21:32 INFO - 2015-12-17 10:21:32,213 INFO : Running cycle 1/1 for v8_7 test... 10:21:32 INFO - 2015-12-17 10:21:32,213 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnL6QhN/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:21:43 INFO - 2015-12-17 10:21:43,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 149929984 10:21:43 INFO - 2015-12-17 10:21:43,992 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57020/tests/v8_7/run.html (next: http://localhost:57020/tests/v8_7/run.html) 10:22:05 INFO - 2015-12-17 10:22:05,758 DEBUG : BROWSER_OUTPUT: RSS: Main: 607207424 10:22:05 INFO - 2015-12-17 10:22:05,758 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,759 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:22:05 INFO - 2015-12-17 10:22:05,759 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:22:05 INFO - 2015-12-17 10:22:05,759 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:22:05 INFO - 2015-12-17 10:22:05,759 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:22:05 INFO - 2015-12-17 10:22:05,759 DEBUG : BROWSER_OUTPUT: |0;Richards;164.7175094712568 10:22:05 INFO - 2015-12-17 10:22:05,759 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.46588767433096 10:22:05 INFO - 2015-12-17 10:22:05,760 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.72027972027973 10:22:05 INFO - 2015-12-17 10:22:05,760 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 10:22:05 INFO - 2015-12-17 10:22:05,760 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1972.3865877712033 10:22:05 INFO - 2015-12-17 10:22:05,760 DEBUG : BROWSER_OUTPUT: |5;Earley;788.0220646178093 10:22:05 INFO - 2015-12-17 10:22:05,761 DEBUG : BROWSER_OUTPUT: |6;Boyer;12072.289156626506 10:22:05 INFO - 2015-12-17 10:22:05,761 DEBUG : BROWSER_OUTPUT: |7;RegExp;25450 10:22:05 INFO - 2015-12-17 10:22:05,761 DEBUG : BROWSER_OUTPUT: |8;Splay;683.526999316473 10:22:05 INFO - 2015-12-17 10:22:05,761 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 10:22:05 INFO - 2015-12-17 10:22:05,762 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:22:05 INFO - 2015-12-17 10:22:05,762 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376525757__endTimestamp 10:22:05 INFO - 2015-12-17 10:22:05,762 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,762 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:22:05 INFO - 2015-12-17 10:22:05,763 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:22:05 INFO - 2015-12-17 10:22:05,763 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,763 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.72 Median:164.72 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,763 DEBUG : BROWSER_OUTPUT: Values: 164.7 10:22:05 INFO - 2015-12-17 10:22:05,764 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,764 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.47 Median:188.47 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,764 DEBUG : BROWSER_OUTPUT: Values: 188.5 10:22:05 INFO - 2015-12-17 10:22:05,764 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,764 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.72 Median:279.72 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,764 DEBUG : BROWSER_OUTPUT: Values: 279.7 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: Values: 5186.5 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1972.39 Median:1972.39 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: Values: 1972.4 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:788.02 Median:788.02 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,765 DEBUG : BROWSER_OUTPUT: Values: 788.0 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12072.29 Median:12072.29 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: Values: 12072.3 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25450.00 Median:25450.00 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: Values: 25450.0 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:683.53 Median:683.53 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: Values: 683.5 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: 10:22:05 INFO - 2015-12-17 10:22:05,766 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 10:22:05 INFO - 2015-12-17 10:22:05,767 DEBUG : BROWSER_OUTPUT: Values: 4826.9 10:22:05 INFO - 2015-12-17 10:22:05,767 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:22:05 INFO - 2015-12-17 10:22:05,767 DEBUG : BROWSER_OUTPUT: 10:22:06 INFO - 2015-12-17 10:22:06,965 INFO : Browser exited with error code: 0 10:22:06 INFO - 2015-12-17 10:22:06,982 INFO : Completed test v8_7 (00:00:52) 10:22:07 INFO - 2015-12-17 10:22:07,368 INFO : Completed test suite (00:21:17) 10:22:07 INFO - 2015-12-17 10:22:07,368 DEBUG : Working with test: dromaeo_css 10:22:07 INFO - 2015-12-17 10:22:07,368 DEBUG : Generating results file: dromaeo_css 10:22:07 INFO - 2015-12-17 10:22:07,369 DEBUG : Working with test: kraken 10:22:07 INFO - 2015-12-17 10:22:07,370 DEBUG : Generating results file: kraken 10:22:07 INFO - 2015-12-17 10:22:07,370 INFO : javascript benchmark 10:22:07 INFO - 2015-12-17 10:22:07,370 DEBUG : Working with test: v8_7 10:22:07 INFO - 2015-12-17 10:22:07,370 DEBUG : Generating results file: v8_7 10:22:07 INFO - 2015-12-17 10:22:07,370 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:22:07 INFO - 2015-12-17 10:22:07,422 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 10:22:07 INFO - 2015-12-17 10:22:07,422 DEBUG : process_Request line: dromaeo_css 6750.10 graph.html#tests=[[72,1,35]] 10:22:07 INFO - 2015-12-17 10:22:07,422 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:22:07 INFO - 2015-12-17 10:22:07,460 DEBUG : process_Request line: kraken 1498.40 graph.html#tests=[[232,1,35]] 10:22:07 INFO - 2015-12-17 10:22:07,460 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:22:07 INFO - 2015-12-17 10:22:07,494 DEBUG : process_Request line: v8_7 19691.85 graph.html#tests=[[230,1,35]] 10:22:07 INFO - 2015-12-17 10:22:07,495 INFO : javascript benchmark 10:22:07 INFO - 2015-12-17 10:22:07,499 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3088.9073327800165, 3102.4570700127474, 3141.978856871517, 3150.1022208670533, 3185.4106990206815, 5043.848684539461, 5153.4846515348445, 5181.455947125554, 5189.1437912744395, 5207.765650545725, 5174.4825517448235, 5199.6360254782185, 5242.475752424755, 5246.265522826808, 5262.9473705262935, 4627.282771170457, 4794.496577859327, 4806.7115973041555, 4822.493638167985, 4864.878378040542, 4742.19382704941, 4815.735134567597, 4832.613390928735, 4885.340479035338, 4900.950990490103, 4734.076855013273, 4799.208130658437, 4830.299606557065, 4844.346013288232, 4872.3422337984275, 4795.2807078938085, 4803.807847686088, 4818.975905120487, 4819.036192761461, 4837.637177211724, 4594.471635761864, 4698.389209402756, 4769.165396055711, 4776.188048031843, 4825.469198388175, 4906.067847109038, 4984.925226121611, 4987.925181122286, 4999.575036121934, 5029.069622119883, 5695.572832037614, 5698.1452782083015, 5700.515456186229, 5701.515371193454, 5705.4294570543025, 5443.1835224716215, 5580.748866301026, 5729.197912292282, 5733.111367738007, 5737.942620573783, 5160.380754309507, 5228.08508511009, 5232.686038837682, 5247.370315562119, 5272.156454967187, 5648.322201335866, 5649.971750141223, 5652.547796176296, 5655.5475561954945, 5662.858428539237, 5439.945600543944, 5487.478689524489, 5488.643238189505, 5493.148561972901, 5539.473749993744, 5521.779128834802, 5609.046462101453, 5618.101103823368, 5620.1288800236025, 5671.177679236487, 5497.587680923947, 5514.889702205934, 5539.19681646159, 5547.916781248285, 5604.271444712162, 5488.670679759227, 5561.0, 5566.805161819317, 5617.9438205618235, 5646.378898321182, 4292.849750258789, 4343.869683909425, 4359.760213188305, 4360.345948107746, 4395.120975804852, 3838.558565764917, 3865.690744740414, 3869.071422858494, 3914.8825535233987, 3930.8624198153498, 5082.364704411949, 5181.870453238624, 5250.921236181461, 5273.789048438096, 5299.788008479618, 5560.7219639017885, 5593.105103183471, 5619.438056194348, 5637.295338082739, 5692.9146062809095, 5343.465653434625, 5360.088784906575, 5360.2227676987395, 5367.865803354869, 5368.812091576776, 4778.402699662543, 4876.780544875489, 4942.0, 4942.851714448572, 4986.650934434628, 5570.470805273492, 5591.944080559224, 5634.295713035953, 5638.802641907513, 5688.82933511987, 5636.830895073154, 5689.857753556194, 5692.231548740867, 5694.0, 5695.658260504383, 5522.061249587499, 5627.634203776742, 5717.656940583578, 5718.25662663851, 5771.740271687868, 5396.9730151348995, 5519.31008623922, 5523.337199536081, 5549.611527193058, 5567.832965011056, 5485.50630443262, 5518.613697041169, 5520.806771762969, 5529.364123125892, 5549.5837812163445, 5239.397469291081, 5369.19462080691, 5383.81156659515, 5387.326584176978, 5412.458754124714, 5447.700376479332, 5498.615096943176, 5512.7519261634125, 5514.724263786875, 5565.526930210977, 5387.488188622075, 5388.991658231806, 5390.164524498552, 5390.946090539202, 5404.513593776579, 5359.571234301325, 5415.864603384947, 5424.105022671373, 5486.177073439016, 5548.889022219614, 5296.285001524745, 5346.144616861284, 5367.221752845893, 5371.704556249444, 5377.489138531833], "unit": "score", "name": "dojo.html", "value": 5156.509162722203}, {"lowerIsBetter": false, "replicates": [549.0885130683067, 589.811260396673, 600.2916558461015, 600.6936462404173, 610.0757352610796, 30134.547981780262, 37700.622993770055, 38483.23033539327, 38497.0, 38585.80707096468, 42364.364534532004, 42381.36427953583, 42638.0, 42770.35844462336, 42897.35653965193, 7516.586587737672, 7625.542467451943, 8579.399442039075, 8745.95627021864, 8752.256058235058, 10440.791184176313, 10675.78648427031, 10682.145428365711, 10682.572697092108, 10683.412412317319, 10274.178065754722, 10312.484375781218, 10347.844782328271, 10362.844557331646, 10378.636747713832, 7849.11621210911, 8038.557879316635, 10241.385516869012, 10322.948385258102, 10328.948355258213, 10332.431716255604, 10576.629817956411, 10582.629607963761, 10585.364878107339, 10585.364878107339, 38191.236175276616, 38505.422418663744, 38886.61113388858, 38908.80545597282, 39020.804895975336, 3222.7744057915957, 3225.3388055448813, 3258.104021394112, 3271.0768205928825, 3308.3052558962645, 29774.255643608867, 30004.69995300041, 30113.69886301131, 30248.69751302503, 30847.0, 3530.770499917497, 3582.5880023797336, 3618.330608837347, 3639.090227443139, 3756.042209236627, 264726.0, 265131.0, 265437.9999999981, 265832.0, 265842.0, 53498.0, 54818.0, 54853.45146548563, 55123.72438137783, 56317.0, 54902.0, 55160.0, 55183.44816551863, 55192.0, 55249.72375138179, 15121.319540620698, 15130.352188367211, 15215.467458638894, 15220.771688424682, 15340.386384544494, 9303.0, 9368.578413971387, 12265.448054837554, 12370.75258494825, 12381.380930953417, 18718.344857929907, 19606.90697859472, 19696.803031969495, 19759.0, 19814.306499272458, 7137.179224389261, 7442.292982166686, 7452.292032256927, 7487.625618719042, 7502.774916752506, 924.9167574918155, 1014.300132908291, 1021.5658345203259, 1033.534909290822, 1047.9677517645114, 26588.0, 27187.1843844685, 29014.854925725238, 29626.259343516584, 29733.0, 1033.6020632056561, 1047.115187666436, 1052.1425038593522, 1107.9861926337471, 1123.1969142063465, 13188.406521706354, 13584.320783960762, 13615.18308901469, 13677.0, 14097.85902140986, 297151.0, 301257.0, 301410.0, 301508.0, 301706.0, 49919.25121123186, 50119.24821127686, 50158.0, 50180.24729629058, 50211.49788502141, 1293.8512071111754, 1307.0981023094225, 1312.1339915655622, 1312.5996571045923, 1320.4850108457708, 25735.871320644026, 26525.86737066302, 26590.7340926596, 26733.866330669, 26740.0, 42638.78680606577, 42784.14942655176, 42841.0, 42900.78549607232, 42910.141797163255, 279919.0, 281370.0, 282267.0, 282518.0, 283446.0, 26891.0, 27417.90328386922, 29302.853485732434, 30346.54480182755, 30393.392132157674, 36649.0, 37373.252534949694, 38573.03567410725, 38636.420453692655, 39581.40627890526, 33247.66752332543, 33452.330953381286, 33456.1635959103, 33595.32809343848, 34151.316973659894, 28745.0, 33163.02004608809, 33690.0, 34018.3196336067, 34132.317353653285, 29162.854185728935, 31367.0, 31516.842415787774, 31541.684583153874, 31566.36867262688, 26617.632442103015, 29164.0, 30141.0, 32327.51508727324, 32333.0, 23850.0, 24085.397865053514, 24250.75749242485, 24550.140745073837, 24579.754202457745], "unit": "score", "name": "ext.html", "value": 18025.593974265004}, {"lowerIsBetter": false, "replicates": [362.18146987807546, 363.71811845819497, 368.10733970122453, 368.37744212281257, 372.5380528145101, 14482.782758258634, 14903.40386384546, 16344.509664710064, 16884.662306753857, 17514.21186046626, 13152.0, 14920.0, 15077.6984460311, 16401.91799041006, 16411.343546258136, 5470.124780035195, 5620.718964051781, 5651.095824668054, 5660.886782264352, 5669.914951275714, 3744.1388480649466, 3858.5369755629363, 5272.1300985337375, 5317.9468205318035, 5606.21512988182, 5428.0, 5457.181422786574, 5480.068388373986, 5487.6981766002855, 5501.9174712379345, 5392.703401312926, 5439.5376393006645, 5782.2483077199695, 6004.489618382443, 6012.488938440194, 5305.734713264322, 5307.65500242483, 5315.415304316522, 5322.387925388591, 5322.387925388591, 11832.585859494962, 20541.8972905136, 20988.895055524776, 21046.368608941757, 21050.47394312677, 1961.901904904749, 2014.5567975045467, 2136.5192831612917, 2158.0720290275176, 2171.4354267890303, 9240.630374785, 9320.25437964968, 9410.294227932933, 9518.238540916711, 9639.759006024835, 1822.4168266154836, 1847.1503108570073, 1860.153630098302, 1878.7557617509635, 1883.9811601883944, 52970.20544691794, 58018.0, 59845.7007714963, 61367.0, 62068.0, 10007.54966026516, 10255.846162307422, 10342.482875856178, 10401.479926003822, 10430.895691042992, 13411.798823017663, 13427.798583021262, 13451.730965380833, 13457.327133643279, 13463.192208467328, 7403.51877127985, 7466.328030477283, 7514.586697731677, 7733.613319334011, 7763.573003484749, 7399.926000740032, 7457.403407727369, 7493.700251989969, 7507.737229196952, 7512.39900807934, 4429.136318417942, 4486.0, 4493.258612328929, 4511.661625378109, 4545.113702827917, 1761.11944027986, 1793.2157503284316, 1801.9909900450414, 1803.6212395396851, 1807.204829874851, 838.2497664590157, 914.0, 967.6951760195516, 985.3742873275521, 1005.8541511480794, 15520.5343839685, 15624.609384765245, 16187.595310117342, 16490.0, 16625.584360390843, 794.7417089445953, 827.3339961331186, 896.744427838076, 984.8178087053918, 985.9211263098935, 5896.086106653475, 5899.675517846474, 6044.274687037496, 6061.242344706911, 8738.86891696625, 385426.0, 390382.0, 395485.0, 399537.0, 400674.0, 79328.60335698284, 80130.59934700289, 80857.59571202107, 81793.0, 82790.17209827945, 499.33338992444766, 499.86753510319795, 504.89397226582093, 505.7976809276319, 507.3227241632462, 9678.370905891095, 9944.900550994398, 10163.84754228702, 10164.33931794431, 10188.789653513863, 35965.0, 36011.0, 36344.09139771421, 37090.44364334591, 37404.81297593604, 46375.304370435144, 48534.27198592095, 48692.75653621709, 49095.26357104575, 52885.0, 9162.725118246197, 9317.301202409712, 9408.20030297405, 9531.380460270339, 9648.75878103053, 11153.944230278797, 11305.773884522428, 11360.318380896842, 11371.0, 13564.0, 14438.566842994727, 14713.632159195677, 14801.703965920406, 14885.776713349525, 15041.774373384189, 14005.089669171472, 14114.435422582981, 14347.210903399995, 14571.271436427924, 14744.0, 11756.529738810352, 11865.169438139592, 12412.62762117138, 14424.423023079378, 15302.311395986984, 9124.726258212264, 9155.908440915506, 9183.586738596645, 9489.762755931157, 9659.758506037406, 3767.962320376761, 8969.237614802814, 14638.926805365905, 14987.775183372476, 15008.47470338533], "unit": "score", "name": "jquery.html", "value": 8225.324848762788}, {"lowerIsBetter": false, "replicates": [1341.1282666266932, 1666.1585899120942, 1721.449136276392, 1736.9044702541364, 1760.3398725477946, 6683.264840867507, 7299.5255308404985, 7438.181800001996, 7438.442116841232, 7459.104907411118, 7387.4459415543915, 7537.886931696029, 7541.170471248172, 7543.132539757929, 7554.395648348132, 1915.9712604310946, 1951.668216403215, 1953.4725624081489, 2170.2729585588845, 2206.2939859244966, 2142.9035693393757, 2181.9563608727817, 2182.028997096285, 2182.432567532445, 2183.4977955070344, 2011.7384739983854, 2024.5849600831793, 2174.510735084601, 2180.8473406861526, 2182.9849120159124, 2150.7956744109283, 2163.7187165668443, 2189.5949249388837, 2190.507135894427, 2191.3206905859233, 2186.4752459409788, 2216.5788500185076, 2219.411855858199, 2223.455253462912, 2223.566404551113, 7458.067741532309, 7555.320021198118, 7572.167061623217, 7573.35626471745, 7580.128285247212, 4434.578715022068, 4503.909921801545, 4531.818727250906, 4538.614217791492, 4545.636349092098, 2878.208492664513, 2880.4815133276, 2936.265933516621, 2948.2629342664336, 2965.0660042086674, 891.6032365597247, 897.7486303834871, 904.3036861616584, 926.3191554207652, 953.2564599612244, 10367.22245831558, 10385.42880141592, 10388.896111038945, 10396.844047339297, 10399.272050956437, 3991.521017477863, 4000.6599439047427, 4045.6763458923215, 4052.2705912935953, 4062.9187416252103, 8870.822583548294, 8876.733697989072, 8887.0, 8887.688930887389, 8888.244499217639, 4409.338599210144, 4415.227335216388, 4416.094700586372, 4419.64642828573, 4425.269830477935, 4001.7198796084576, 4029.9194016119513, 4031.496062992126, 4031.91936161281, 4038.5759495252573, 3084.5373194021076, 3117.1583672408774, 3396.100033491127, 3516.4197907345515, 3581.9104522387147, 1128.418864284894, 1132.841402203692, 1135.0, 1136.710138914588, 1156.0231604294358, 987.1757082835769, 1006.3207335048813, 1010.0303708439833, 1100.510272928539, 1269.2701696524534, 6989.33601307875, 7059.788206353817, 7067.081279433641, 7074.115735533059, 7079.0, 1075.5858994287255, 1081.6592773276393, 1085.9674209773718, 1092.4592326798286, 1136.9772604547863, 5539.723013849291, 5622.8313150605545, 5627.971860140755, 5629.324481062217, 5639.43605639441, 8643.783905402415, 8933.240674542736, 8953.238974687225, 8971.102889710977, 8976.281897448189, 8788.384813063025, 8842.734717958343, 8862.379633425599, 8866.556672166365, 8867.822643547093, 6896.862062758817, 6981.965090174516, 6993.89509157363, 7006.579605223805, 7057.223705392403, 6798.592084474947, 6807.115075040213, 6820.283870193558, 6821.93178068213, 6839.555428897304, 7839.960800195962, 7924.9207507926485, 7973.920260797549, 8023.6389362479995, 8035.638396272065, 8047.678092876454, 8195.057568379712, 8298.83402331938, 8329.167083291622, 8338.708145214889, 6488.70800813974, 6528.967355163194, 6565.113709649328, 6846.520743548103, 6850.212225593921, 6095.634261944298, 6305.4009869062365, 6709.094272273376, 6835.384815366641, 7033.050538177281, 6521.413072823469, 6540.182477190351, 6592.637404942774, 6689.531732778659, 6761.391474767294, 6533.444657204191, 6556.98366753154, 6599.274079851309, 6663.83340416474, 6679.966600166968, 6549.7707580236365, 6583.670816459062, 6588.967055164693, 6598.175228096488, 6603.570767900071, 6116.602420842631, 6123.173371594956, 6124.908126378019, 6126.938730612816, 6130.969345153246, 6079.908801367895, 6338.968305158444, 6361.0, 6365.045243213555, 6371.553991220755], "unit": "score", "name": "mootools.html", "value": 4275.661068088338}, {"lowerIsBetter": false, "replicates": [321.7104605854731, 330.44485264755207, 336.93864327368794, 338.8661478715909, 343.35620711166564, 5954.106883967407, 5958.970205148974, 5971.820845374635, 5996.580239383245, 6000.7299671514775, 5654.293213348332, 5762.4525670061275, 5797.739101740422, 5897.351291357958, 5921.171036054956, 4541.386912766768, 4558.635309175258, 4581.931271030937, 4582.9770851145695, 4590.173768721629, 4403.185410699031, 4583.220852455091, 4583.9541604583865, 4584.656150788687, 4588.128255631436, 4430.4240448741775, 4467.776611169445, 4533.0, 4559.658025648073, 4575.771211439432, 4542.0, 4557.612602928755, 4571.3600095986585, 4576.336431217472, 4577.816887324503, 4014.2172276406113, 4019.618136277049, 4021.658159056484, 4024.3359845625437, 4028.3554631258858, 5536.667799932017, 5656.434356564352, 5664.773409063632, 5666.716664166775, 5671.291088613923, 5280.49835265653, 5296.496832800878, 5298.814541491068, 5304.840854774363, 5305.814296499604, 6234.625922444668, 6288.937110628926, 6289.276733175698, 6292.276388215322, 6293.709789493143, 4418.204723149832, 4422.248217802981, 4423.51341352451, 4423.557644235583, 4430.889227769299, 6328.955722305814, 6495.772647957346, 6519.706613202417, 6527.967360163168, 6536.05227242052, 4517.977410112929, 4523.7285762854335, 4525.547445255514, 4529.796159172845, 4541.318802179633, 4578.198815207325, 4579.610733087658, 4586.151561961048, 4587.655925805578, 4588.128255631419, 5276.050310944067, 5276.947230527646, 5281.577473802164, 5282.392524859613, 5284.524392804666, 6174.38256174379, 6296.622202667854, 6312.242530896322, 6321.209848768904, 6331.556791024584, 1614.0638429710975, 1636.5090472858094, 1636.6235765773702, 1637.1568642149302, 1638.221844623818, 1097.533548241994, 1098.5880294889416, 1101.1741194104422, 1110.7334239782394, 1135.330155208431, 6083.239595050619, 6089.604175728564, 6094.786682466181, 6095.603785753912, 6096.634201947897, 6537.280899101095, 6543.509236807258, 6551.868962620721, 6553.246376666744, 6561.967190164019, 5926.0, 5991.730372133265, 6082.087686847009, 6101.542384321194, 6113.84715382119, 3525.8765943191866, 3539.0267676388685, 3549.290141971616, 3582.30145121704, 3590.9461358079648, 6727.529072964943, 6728.3271672832325, 6731.427828634523, 6733.595984240961, 6738.663066846736, 6626.9337306627285, 6757.121574195421, 6762.864742705118, 6775.22084960226, 6778.237658208901, 6733.259341472434, 6733.663316834139, 6733.93266067333, 6736.966315168393, 6740.865182696319, 6592.736290548324, 6604.339566043357, 6606.042123892104, 6610.206775187008, 6611.33886611335, 6345.968270158804, 6521.510886683613, 6534.4772418205475, 6540.771073012422, 6544.83637909037, 6721.764738234138, 6724.630145342053, 6725.865482690221, 6726.966365168143, 6729.629870357177, 4502.234620114654, 4529.433820772403, 4530.25250833622, 4535.31970204472, 4538.568835960578, 6373.458256048288, 6518.902216466851, 6526.118973938457, 6534.1178940844275, 6547.0, 6347.90478142819, 6349.6507692077375, 6356.904646430215, 6358.491320694426, 6369.108324834619, 6324.747010119544, 6334.049892516159, 6338.0, 6346.174997250328, 6349.238091429058, 6140.416660417253, 6228.4705800007505, 6290.748370065146, 6305.96847015762, 6327.430531252209, 6425.1968503937005, 6428.260750013808, 6428.839279017875, 6436.806895793134, 6449.774257900951, 6311.495080393649, 6316.526260530571, 6316.62100273985, 6318.08387783778, 6318.399752023551], "unit": "score", "name": "prototype.html", "value": 4788.383617426973}, {"lowerIsBetter": false, "replicates": [759.1307952394509, 769.6921231507397, 812.6180695073314, 845.5983407881255, 851.8594431918734, 11417.714557136065, 11651.592194273202, 11653.766924661502, 11675.299482031083, 11679.532818687243, 11822.645320640373, 11824.29054256743, 13169.736605267914, 13434.261115638636, 13453.394597243123, 801.6432687454085, 815.2784785464877, 815.3640160674684, 815.6818840652151, 816.0574536410426, 678.4979115454578, 686.8729285082251, 794.2494342846013, 816.1145157504086, 819.7171975668415, 852.6930305090162, 853.8975322961222, 858.4849090545686, 858.5149390594325, 859.3168431097262, 710.3571268002466, 852.0499642898155, 855.315747402082, 856.1395797223781, 856.6787454704486, 527.4066674990636, 616.6577549460048, 625.9906101408483, 626.9122322874802, 627.3976982097192, 11988.220765650205, 12222.511099556006, 12279.631611051684, 12288.447019884037, 12293.69265768354, 11250.0, 11283.774324513546, 12056.457459414347, 12340.0, 12523.874761252362, 8722.607482663296, 10117.392956422638, 10381.905452810834, 10659.147268218601, 10907.618233361874, 3638.708903287757, 3680.2271522980204, 3706.814659267026, 3770.830312635938, 3775.5846856845724, 49084.0, 49460.50539494595, 49499.0, 49568.0, 49610.0, 8529.360297977677, 9079.773005674779, 9504.42973421597, 9691.12779849817, 10349.94825025885, 16350.427735029454, 16450.506484805475, 16478.588035299214, 16492.25784839685, 16556.6688666226, 7024.929750702529, 7241.5293005954445, 7286.834106542928, 7355.264473552709, 7462.0, 3803.733738638324, 3841.654251117413, 3867.7292589518465, 3900.5124359455067, 3910.0029492479234, 3028.303490197267, 3075.7693173012112, 3081.6302043754895, 3081.830499322514, 3082.799618024821, 548.8243761996123, 558.4443478738681, 561.50026476436, 568.139269007454, 575.6977586766897, 573.0973716396692, 576.5848589015902, 598.4105655928906, 621.487272999777, 686.8935315026179, 8205.261526462647, 8207.589620518951, 8210.917890821134, 8258.256756891788, 8450.366222533212, 533.7518054104783, 558.3132746721474, 596.0879853823657, 610.4993904997859, 619.0961196652847, 3871.554771201348, 3973.2848087343978, 3974.5429275633674, 3979.6617287530307, 4088.100617864065, 61077.38922610806, 61248.0, 61778.0, 61885.07172392418, 61924.0, 55437.1684424734, 56757.14864277039, 56771.432285676616, 56820.0, 56915.430845691015, 49444.75277623661, 49660.25509617358, 50217.24674129818, 50229.74885125624, 51164.0, 7601.353884919844, 7646.770596882103, 7688.269614386624, 7741.535507869546, 7889.13219545838, 35746.28507429785, 35839.0, 36088.27823443569, 36167.27665446624, 37141.62858371382, 44900.77549612231, 44933.550664492934, 45951.77024114858, 46771.532284676716, 47584.286235707186, 9274.768130796514, 9301.162895339181, 9308.767280817763, 9374.531273436438, 9728.367656102331, 10088.848667270144, 10484.26610137283, 10666.679999600025, 10695.358278503314, 10751.677449676523, 9098.772530686785, 9152.725418237464, 9331.72004839856, 9450.669226577313, 9607.663731769355, 9610.807783844424, 9698.9030109698, 9706.85439718419, 10030.64892728751, 10158.13655839262, 8828.382013259266, 9955.601775928882, 10145.137663298703, 10151.64469243573, 10655.30740501865, 8833.160849719266, 9154.176124148858, 9234.907650923406, 9277.443353398818, 9411.341206115507, 9173.220276276592, 9228.769280768034, 9403.858942115738, 9429.434233945985, 9550.90449095528], "unit": "score", "name": "yui.html", "value": 6042.734288522181}], "name": "dromaeo_css", "value": 6750.105478157376}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 106.0, 110.0, 110.0, 111.0, 99.0, 109.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.3}, {"lowerIsBetter": true, "replicates": [151.0, 127.0, 157.0, 163.0, 134.0, 155.0, 145.0, 134.0, 173.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 147.1}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 160.0, 161.0, 160.0, 159.0, 159.0, 161.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [71.0, 83.0, 86.0, 90.0, 72.0, 72.0, 70.0, 94.0, 87.0, 73.0], "unit": "score", "name": "audio-fft", "value": 79.8}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 146.0, 93.0, 93.0, 93.0, 132.0, 93.0, 94.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [147.0, 145.0, 144.0, 145.0, 147.0, 146.0, 146.0, 146.0, 145.0, 144.0], "unit": "score", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 57.0, 57.0, 57.0, 58.0, 64.0, 55.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 69.0, 68.0, 69.0, 69.0, 69.0, 68.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 84.0, 91.0, 83.0, 92.0, 83.0, 85.0, 85.0, 91.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.1}, {"lowerIsBetter": true, "replicates": [116.0, 121.0, 123.0, 113.0, 120.0, 123.0, 122.0, 119.0, 130.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.6}, {"lowerIsBetter": true, "replicates": [138.0, 153.0, 146.0, 146.0, 150.0, 152.0, 147.0, 147.0, 146.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.2}, {"lowerIsBetter": true, "replicates": [68.0, 66.0, 69.0, 69.0, 67.0, 67.0, 67.0, 68.0, 66.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.4}], "name": "kraken", "value": 1498.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.7175094712568], "unit": "score", "name": "Richards", "value": 214.31844199999998}, {"lowerIsBetter": false, "replicates": [188.46588767433096], "unit": "score", "name": "DeltaBlue", "value": 350.8221079999999}, {"lowerIsBetter": false, "replicates": [279.72027972027973], "unit": "score", "name": "Encrypt", "value": 951.5970750000004}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [1972.3865877712033], "unit": "score", "name": "RayTrace", "value": 375.1744229999998}, {"lowerIsBetter": false, "replicates": [788.0220646178093], "unit": "score", "name": "Earley", "value": 845.7415470000001}, {"lowerIsBetter": false, "replicates": [12072.289156626506], "unit": "score", "name": "Boyer", "value": 55.20601696606782}, {"lowerIsBetter": false, "replicates": [25450.0], "unit": "score", "name": "RegExp", "value": 35.79508840864438}, {"lowerIsBetter": false, "replicates": [683.526999316473], "unit": "score", "name": "Splay", "value": 119.22133299999997}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19691.845307799893}]} 10:22:07 INFO - RETURN: dromaeo_css: 6750.10 10:22:07 INFO - RETURN: kraken: 1498.40 10:22:07 INFO - RETURN: v8_7: 19691.85 10:22:07 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19691.85"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1498.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6750.10"}}} 10:22:07 INFO - Return code: 0 10:22:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:22:07 INFO - # TBPL SUCCESS # 10:22:07 INFO - Running post-action listener: _resource_record_post_action 10:22:07 INFO - Running post-run listener: _resource_record_post_run 10:22:08 INFO - Total resource usage - Wall time: 1309s; CPU: 13.0%; Read bytes: 0; Write bytes: 567300096; Read time: 0; Write time: 5079872 10:22:08 INFO - install - Wall time: 22s; CPU: 23.0%; Read bytes: 0; Write bytes: 154255360; Read time: 0; Write time: 1255988 10:22:08 INFO - run-tests - Wall time: 1288s; CPU: 13.0%; Read bytes: 0; Write bytes: 396627968; Read time: 0; Write time: 3815060 10:22:08 INFO - Running post-run listener: _upload_blobber_files 10:22:08 INFO - Blob upload gear active. 10:22:08 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:22:08 INFO - Copying logs to upload dir... 10:22:08 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1349.782175 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2015-12-17 10:22:08.285012) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 10:22:08.288477) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010148 build_url: 'https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 10:22:08.340054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 10:22:08.340420) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=256b35f542ca8bede9e27f1f00000291-1450374254.438646-257923533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 10:22:08.389808) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 10:22:08.390162) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 10:22:08.390492) ========= ========= Total master_lag: 0.24 =========