builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1453722372.63 results: success (0) buildid: 20160125001712 builduid: 5d8eb007e2de4c2eae63c4ce4fd78a1e revision: ab89c814f1b8a841168de98ac2679a1d66122a06 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.632823) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.633182) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.633477) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006337 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.08 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.718607) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.718875) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.887232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.887483) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.966248) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.966505) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.966851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:12.967130) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-25 03:46:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.2M=0s 2016-01-25 03:46:13 (47.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.179981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:13.170783) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:13.171144) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020442 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:13.222736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:13.223043) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ab89c814f1b8a841168de98ac2679a1d66122a06 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ab89c814f1b8a841168de98ac2679a1d66122a06 --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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-25 03:46:13,728 truncating revision to first 12 chars 2016-01-25 03:46:13,728 Setting DEBUG logging. 2016-01-25 03:46:13,728 attempt 1/10 2016-01-25 03:46:13,728 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ab89c814f1b8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-25 03:46:14,248 unpacking tar archive at: mozilla-aurora-ab89c814f1b8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.822256 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:14.057974) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:14.058283) ========= echo ab89c814f1b8a841168de98ac2679a1d66122a06 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'ab89c814f1b8a841168de98ac2679a1d66122a06'] 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False ab89c814f1b8a841168de98ac2679a1d66122a06 program finished with exit code 0 elapsedTime=0.004665 script_repo_revision: 'ab89c814f1b8a841168de98ac2679a1d66122a06' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:14.108743) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:14.109081) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-25 03:46:14.142847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 31 secs) (at 2016-01-25 03:46:14.143128) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:14 INFO - MultiFileLogger online at 20160125 03:46:14 in /builds/slave/test-pgo 03:46:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:46:14 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 03:46:14 INFO - {'append_to_log': False, 03:46:14 INFO - 'base_work_dir': '/builds/slave/test-pgo', 03:46:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:46:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 03:46:14 INFO - 'branch': 'Mozilla-Aurora', 03:46:14 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:14 INFO - 'config_files': ('talos/linux_config.py',), 03:46:14 INFO - 'default_actions': ('clobber', 03:46:14 INFO - 'read-buildbot-config', 03:46:14 INFO - 'download-and-extract', 03:46:14 INFO - 'populate-webroot', 03:46:14 INFO - 'create-virtualenv', 03:46:14 INFO - 'install', 03:46:14 INFO - 'run-tests'), 03:46:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:14 INFO - 'download_minidump_stackwalk': True, 03:46:14 INFO - 'download_symbols': 'ondemand', 03:46:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:46:14 INFO - 'tooltool.py': '/tools/tooltool.py', 03:46:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:46:14 INFO - '/tools/misc-python/virtualenv.py')}, 03:46:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:14 INFO - 'installer_path': 'installer.exe', 03:46:14 INFO - 'log_level': 'info', 03:46:14 INFO - 'log_name': 'talos', 03:46:14 INFO - 'log_to_console': True, 03:46:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:14 INFO - 'opt_config_files': (), 03:46:14 INFO - 'pip_index': False, 03:46:14 INFO - 'sps_profile': False, 03:46:14 INFO - 'sps_profile_interval': 0, 03:46:14 INFO - 'suite': 'dromaeojs', 03:46:14 INFO - 'system_bits': '32', 03:46:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:14 INFO - 'title': 'talos-linux64-ix-033', 03:46:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:14 INFO - 'use_talos_json': True, 03:46:14 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 03:46:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:14 INFO - 'work_dir': 'build'} 03:46:14 INFO - ##### 03:46:14 INFO - ##### Running clobber step. 03:46:14 INFO - ##### 03:46:14 INFO - Running pre-action listener: _resource_record_pre_action 03:46:14 INFO - Running main action method: clobber 03:46:14 INFO - rmtree: /builds/slave/test-pgo/build 03:46:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 03:46:15 INFO - Running post-action listener: _resource_record_post_action 03:46:15 INFO - ##### 03:46:15 INFO - ##### Running read-buildbot-config step. 03:46:15 INFO - ##### 03:46:15 INFO - Running pre-action listener: _resource_record_pre_action 03:46:15 INFO - Running main action method: read_buildbot_config 03:46:15 INFO - Using buildbot properties: 03:46:15 INFO - { 03:46:15 INFO - "properties": { 03:46:15 INFO - "buildnumber": 118, 03:46:15 INFO - "product": "firefox", 03:46:15 INFO - "script_repo_revision": "production", 03:46:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:46:15 INFO - "repository": "", 03:46:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:46:15 INFO - "buildid": "20160125001712", 03:46:15 INFO - "slavename": "talos-linux64-ix-033", 03:46:15 INFO - "pgo_build": "True", 03:46:15 INFO - "basedir": "/builds/slave/test-pgo", 03:46:15 INFO - "project": "", 03:46:15 INFO - "platform": "ubuntu64_hw", 03:46:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:46:15 INFO - "slavebuilddir": "test", 03:46:15 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:46:15 INFO - "branch": "mozilla-aurora", 03:46:15 INFO - "repo_path": "releases/mozilla-aurora", 03:46:15 INFO - "stage_platform": "linux64-pgo", 03:46:15 INFO - "builduid": "5d8eb007e2de4c2eae63c4ce4fd78a1e", 03:46:15 INFO - "revision": "ab89c814f1b8a841168de98ac2679a1d66122a06" 03:46:15 INFO - }, 03:46:15 INFO - "sourcestamp": { 03:46:15 INFO - "repository": "", 03:46:15 INFO - "hasPatch": false, 03:46:15 INFO - "project": "", 03:46:15 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 03:46:15 INFO - "changes": [ 03:46:15 INFO - { 03:46:15 INFO - "category": null, 03:46:15 INFO - "files": [ 03:46:15 INFO - { 03:46:15 INFO - "url": null, 03:46:15 INFO - "name": "https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 03:46:15 INFO - } 03:46:15 INFO - ], 03:46:15 INFO - "repository": "", 03:46:15 INFO - "rev": "ab89c814f1b8a841168de98ac2679a1d66122a06", 03:46:15 INFO - "who": "cbook@mozilla.com", 03:46:15 INFO - "when": 1453717351, 03:46:15 INFO - "number": 7510169, 03:46:15 INFO - "comments": "Bug 1241061 - [firefox-ui-tests] Workaround for bug 1237706 to use ZipFile if unzip command is not available. r=maja_zf, a=test-only", 03:46:15 INFO - "project": "", 03:46:15 INFO - "at": "Mon 25 Jan 2016 02:22:31", 03:46:15 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 03:46:15 INFO - "revlink": "", 03:46:15 INFO - "properties": [ 03:46:15 INFO - [ 03:46:15 INFO - "buildid", 03:46:15 INFO - "20160125001712", 03:46:15 INFO - "Change" 03:46:15 INFO - ], 03:46:15 INFO - [ 03:46:15 INFO - "builduid", 03:46:15 INFO - "5d8eb007e2de4c2eae63c4ce4fd78a1e", 03:46:15 INFO - "Change" 03:46:15 INFO - ], 03:46:15 INFO - [ 03:46:15 INFO - "pgo_build", 03:46:15 INFO - "True", 03:46:15 INFO - "Change" 03:46:15 INFO - ] 03:46:15 INFO - ], 03:46:15 INFO - "revision": "ab89c814f1b8a841168de98ac2679a1d66122a06" 03:46:15 INFO - } 03:46:15 INFO - ], 03:46:15 INFO - "revision": "ab89c814f1b8a841168de98ac2679a1d66122a06" 03:46:15 INFO - } 03:46:15 INFO - } 03:46:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 03:46:15 INFO - Running post-action listener: _resource_record_post_action 03:46:15 INFO - ##### 03:46:15 INFO - ##### Running download-and-extract step. 03:46:15 INFO - ##### 03:46:15 INFO - Running pre-action listener: _resource_record_pre_action 03:46:15 INFO - Running main action method: download_and_extract 03:46:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 03:46: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')]} 03:46:15 INFO - https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:46:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/test_packages.json 03:46:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/test_packages.json 03:46:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 03:46:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 03:46:32 INFO - Downloaded 1302 bytes. 03:46:32 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 03:46:32 INFO - Using the following test package requirements: 03:46:32 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:32 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:46:32 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'jsshell-linux-x86_64.zip'], 03:46:32 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:46:32 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:32 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:46:32 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:46:32 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:46:32 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:32 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:32 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:46:32 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:32 INFO - https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:46:37 INFO - Downloaded 23159167 bytes. 03:46:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 03:46:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:46:39 INFO - Return code: 0 03:46:39 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:39 INFO - https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:47:09 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip: timed out 03:47:09 INFO - retry: attempt #1 caught exception: timed out 03:47:09 INFO - retry: Failed, sleeping 30 seconds before retrying 03:47:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:47:45 INFO - Downloaded 23159167 bytes. 03:47:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 03:47:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 03:47:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:47:46 INFO - Return code: 0 03:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:46 INFO - https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:47:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 03:47:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 03:47:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 03:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:47:47 INFO - Downloaded 11132038 bytes. 03:47:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 03:47:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 03:47:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 03:47:48 INFO - Return code: 0 03:47:48 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')]} 03:47:48 INFO - https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:47:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 03:47:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 03:47:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 03:47:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:47:49 INFO - Downloaded 58033452 bytes. 03:47:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 03:47:49 INFO - mkdir: /builds/slave/test-pgo/properties 03:47:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:47:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:47:49 INFO - Contents: 03:47:49 INFO - build_url:https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 03:47:49 INFO - Running post-action listener: _resource_record_post_action 03:47:49 INFO - Running post-action listener: set_extra_try_arguments 03:47:49 INFO - ##### 03:47:49 INFO - ##### Running populate-webroot step. 03:47:49 INFO - ##### 03:47:49 INFO - Running pre-action listener: _resource_record_pre_action 03:47:49 INFO - Running main action method: populate_webroot 03:47:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:47:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:47:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:47:49 INFO - '--tppagecycles', 03:47:49 INFO - '7'], 03:47:49 INFO - 'tests': ['tsvgm']}}, 03:47:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:47:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:47:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:47:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:47:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'tests': ['damp', 'tps']}, 03:47:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['damp', 'tps']}, 03:47:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:47:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:47:49 INFO - 'other': {'tests': ['a11yr', 03:47:49 INFO - 'ts_paint', 03:47:49 INFO - 'tpaint', 03:47:49 INFO - 'sessionrestore', 03:47:49 INFO - 'sessionrestore_no_auto_restore']}, 03:47:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['a11yr', 03:47:49 INFO - 'ts_paint', 03:47:49 INFO - 'tpaint', 03:47:49 INFO - 'sessionrestore', 03:47:49 INFO - 'sessionrestore_no_auto_restore']}, 03:47:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['a11yr', 03:47:49 INFO - 'ts_paint', 03:47:49 INFO - 'tpaint', 03:47:49 INFO - 'sessionrestore', 03:47:49 INFO - 'sessionrestore_no_auto_restore']}, 03:47:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['a11yr', 03:47:49 INFO - 'ts_paint', 03:47:49 INFO - 'tpaint', 03:47:49 INFO - 'sessionrestore', 03:47:49 INFO - 'sessionrestore_no_auto_restore']}, 03:47:49 INFO - 'other_l64': {'tests': ['a11yr', 03:47:49 INFO - 'ts_paint', 03:47:49 INFO - 'tpaint', 03:47:49 INFO - 'sessionrestore', 03:47:49 INFO - 'sessionrestore_no_auto_restore']}, 03:47:49 INFO - 'other_nol64': {'tests': ['a11yr', 03:47:49 INFO - 'ts_paint', 03:47:49 INFO - 'tpaint', 03:47:49 INFO - 'sessionrestore', 03:47:49 INFO - 'sessionrestore_no_auto_restore']}, 03:47:49 INFO - 'svgr': {'tests': ['tsvgx', 03:47:49 INFO - 'tsvgr_opacity', 03:47:49 INFO - 'tart', 03:47:49 INFO - 'tscrollx', 03:47:49 INFO - 'cart']}, 03:47:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['tsvgx', 03:47:49 INFO - 'tsvgr_opacity', 03:47:49 INFO - 'tart', 03:47:49 INFO - 'tscrollx', 03:47:49 INFO - 'cart']}, 03:47:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'tests': ['tp5o']}, 03:47:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'talos_options': ['--e10s'], 03:47:49 INFO - 'tests': ['tp5o']}, 03:47:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'talos_options': ['--xperf_path', 03:47:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:49 INFO - 'tests': ['tp5n']}, 03:47:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:49 INFO - 'talos_options': ['--e10s', 03:47:49 INFO - '--xperf_path', 03:47:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:49 INFO - 'tests': ['tp5n']}}, 03:47:49 INFO - 'talos.zip': {'path': '', 03:47:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:47:49 INFO - Running post-action listener: _resource_record_post_action 03:47:49 INFO - ##### 03:47:49 INFO - ##### Running create-virtualenv step. 03:47:49 INFO - ##### 03:47:49 INFO - Running pre-action listener: _resource_record_pre_action 03:47:49 INFO - Running main action method: create_virtualenv 03:47:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:47:49 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 03:47:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:47:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:47:49 INFO - Using real prefix '/usr' 03:47:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:47:50 INFO - Installing distribute.............................................................................................................................................................................................done. 03:47:52 INFO - Installing pip.................done. 03:47:52 INFO - Return code: 0 03:47:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:47:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 03:47:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:47:52 INFO - Using env: {'DISPLAY': ':0', 03:47:52 INFO - 'HOME': '/home/cltbld', 03:47:52 INFO - 'LANG': 'en_US.UTF-8', 03:47:52 INFO - 'LANGUAGE': 'en_US:en', 03:47:52 INFO - 'LOGNAME': 'cltbld', 03:47:52 INFO - 'MAIL': '/var/mail/cltbld', 03:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:52 INFO - 'MOZ_NO_REMOTE': '1', 03:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:52 INFO - 'NO_EM_RESTART': '1', 03:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:52 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:52 INFO - 'SHELL': '/bin/bash', 03:47:52 INFO - 'SHLVL': '1', 03:47:52 INFO - 'TERM': 'linux', 03:47:52 INFO - 'TMOUT': '86400', 03:47:52 INFO - 'USER': 'cltbld', 03:47:52 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:52 INFO - '_': '/tools/buildbot/bin/python'} 03:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:52 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 03:47:52 INFO - Cleaning up... 03:47:52 INFO - Return code: 0 03:47:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:47:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 03:47:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:47:52 INFO - Using env: {'DISPLAY': ':0', 03:47:52 INFO - 'HOME': '/home/cltbld', 03:47:52 INFO - 'LANG': 'en_US.UTF-8', 03:47:52 INFO - 'LANGUAGE': 'en_US:en', 03:47:52 INFO - 'LOGNAME': 'cltbld', 03:47:52 INFO - 'MAIL': '/var/mail/cltbld', 03:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:52 INFO - 'MOZ_NO_REMOTE': '1', 03:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:52 INFO - 'NO_EM_RESTART': '1', 03:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:52 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:52 INFO - 'SHELL': '/bin/bash', 03:47:52 INFO - 'SHLVL': '1', 03:47:52 INFO - 'TERM': 'linux', 03:47:52 INFO - 'TMOUT': '86400', 03:47:52 INFO - 'USER': 'cltbld', 03:47:52 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:52 INFO - '_': '/tools/buildbot/bin/python'} 03:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:52 INFO - Downloading/unpacking psutil>=0.7.1 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:47:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:54 INFO - Installing collected packages: psutil 03:47:54 INFO - Running setup.py install for psutil 03:47:54 INFO - building 'psutil._psutil_linux' extension 03:47:54 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 03:47:54 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 03:47:54 INFO - building 'psutil._psutil_posix' extension 03:47:54 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 03:47:55 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 03:47:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:55 INFO - Successfully installed psutil 03:47:55 INFO - Cleaning up... 03:47:55 INFO - Return code: 0 03:47:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:47:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 03:47:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:47:55 INFO - Using env: {'DISPLAY': ':0', 03:47:55 INFO - 'HOME': '/home/cltbld', 03:47:55 INFO - 'LANG': 'en_US.UTF-8', 03:47:55 INFO - 'LANGUAGE': 'en_US:en', 03:47:55 INFO - 'LOGNAME': 'cltbld', 03:47:55 INFO - 'MAIL': '/var/mail/cltbld', 03:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:55 INFO - 'MOZ_NO_REMOTE': '1', 03:47:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:55 INFO - 'NO_EM_RESTART': '1', 03:47:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:55 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:55 INFO - 'SHELL': '/bin/bash', 03:47:55 INFO - 'SHLVL': '1', 03:47:55 INFO - 'TERM': 'linux', 03:47:55 INFO - 'TMOUT': '86400', 03:47:55 INFO - 'USER': 'cltbld', 03:47:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:55 INFO - '_': '/tools/buildbot/bin/python'} 03:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:47:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:47:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:47:57 INFO - Installing collected packages: mozsystemmonitor 03:47:57 INFO - Running setup.py install for mozsystemmonitor 03:47:57 INFO - Successfully installed mozsystemmonitor 03:47:57 INFO - Cleaning up... 03:47:57 INFO - Return code: 0 03:47:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:47: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')]} 03:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47: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')]} 03:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:47:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 03:47:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:47:57 INFO - Using env: {'DISPLAY': ':0', 03:47:57 INFO - 'HOME': '/home/cltbld', 03:47:57 INFO - 'LANG': 'en_US.UTF-8', 03:47:57 INFO - 'LANGUAGE': 'en_US:en', 03:47:57 INFO - 'LOGNAME': 'cltbld', 03:47:57 INFO - 'MAIL': '/var/mail/cltbld', 03:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:57 INFO - 'MOZ_NO_REMOTE': '1', 03:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:57 INFO - 'NO_EM_RESTART': '1', 03:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:47:57 INFO - 'PWD': '/builds/slave/test-pgo', 03:47:57 INFO - 'SHELL': '/bin/bash', 03:47:57 INFO - 'SHLVL': '1', 03:47:57 INFO - 'TERM': 'linux', 03:47:57 INFO - 'TMOUT': '86400', 03:47:57 INFO - 'USER': 'cltbld', 03:47:57 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:57 INFO - '_': '/tools/buildbot/bin/python'} 03:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:57 INFO - Downloading/unpacking blobuploader==1.2.4 03:47: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 03:47: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 03:47: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 03:47: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 03:47: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 03:47: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 03:47:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:47:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:47:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:47:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:00 INFO - Downloading docopt-0.6.1.tar.gz 03:48:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:48:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:48:00 INFO - Running setup.py install for blobuploader 03:48:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:48:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:48:00 INFO - Running setup.py install for requests 03:48:00 INFO - Running setup.py install for docopt 03:48:00 INFO - Successfully installed blobuploader requests docopt 03:48:00 INFO - Cleaning up... 03:48:00 INFO - Return code: 0 03:48:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:48: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')]} 03:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:48:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 03:48:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 03:48:00 INFO - Using env: {'DISPLAY': ':0', 03:48:00 INFO - 'HOME': '/home/cltbld', 03:48:00 INFO - 'LANG': 'en_US.UTF-8', 03:48:00 INFO - 'LANGUAGE': 'en_US:en', 03:48:00 INFO - 'LOGNAME': 'cltbld', 03:48:00 INFO - 'MAIL': '/var/mail/cltbld', 03:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:00 INFO - 'MOZ_NO_REMOTE': '1', 03:48:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:00 INFO - 'NO_EM_RESTART': '1', 03:48:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:48:00 INFO - 'PWD': '/builds/slave/test-pgo', 03:48:00 INFO - 'SHELL': '/bin/bash', 03:48:00 INFO - 'SHLVL': '1', 03:48:00 INFO - 'TERM': 'linux', 03:48:00 INFO - 'TMOUT': '86400', 03:48:00 INFO - 'USER': 'cltbld', 03:48:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:00 INFO - '_': '/tools/buildbot/bin/python'} 03:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:48:00 INFO - Running setup.py (path:/tmp/pip-c8bhRY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:48:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:48:00 INFO - Running setup.py (path:/tmp/pip-Hd4M2U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:48:01 INFO - Running setup.py (path:/tmp/pip-mvmjL0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:48:01 INFO - Running setup.py (path:/tmp/pip-NMXCll-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:48:01 INFO - Running setup.py (path:/tmp/pip-LQuMq2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:48:01 INFO - Running setup.py (path:/tmp/pip-gCM98q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:48:01 INFO - Running setup.py (path:/tmp/pip-U5_DgB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:48:01 INFO - Running setup.py (path:/tmp/pip-f0yELZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:48:01 INFO - Running setup.py (path:/tmp/pip-bqgs3y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:48:01 INFO - Running setup.py (path:/tmp/pip-uyfgVN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:48:01 INFO - Running setup.py (path:/tmp/pip-RkJ8c2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:48:01 INFO - Running setup.py (path:/tmp/pip-G8w7J_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:48:01 INFO - Running setup.py (path:/tmp/pip-zbOpr7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:48:01 INFO - Running setup.py (path:/tmp/pip-fUD4tn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:48:01 INFO - Running setup.py (path:/tmp/pip-7pMXdo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:48:02 INFO - Running setup.py (path:/tmp/pip-l5hOKt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:48:02 INFO - Running setup.py (path:/tmp/pip-8CP13F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:48:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:48:02 INFO - Running setup.py install for manifestparser 03:48:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Running setup.py install for mozcrash 03:48:02 INFO - Running setup.py install for mozdebug 03:48:02 INFO - Running setup.py install for mozdevice 03:48:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Running setup.py install for mozfile 03:48:02 INFO - Running setup.py install for mozhttpd 03:48:02 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Running setup.py install for mozinfo 03:48:02 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Running setup.py install for mozInstall 03:48:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:48:02 INFO - Running setup.py install for mozleak 03:48:03 INFO - Running setup.py install for mozlog 03:48:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Running setup.py install for moznetwork 03:48:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Running setup.py install for mozprocess 03:48:03 INFO - Running setup.py install for mozprofile 03:48:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Running setup.py install for mozrunner 03:48:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Running setup.py install for mozscreenshot 03:48:03 INFO - Running setup.py install for moztest 03:48:03 INFO - Running setup.py install for mozversion 03:48:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:48:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:48:03 INFO - Cleaning up... 03:48:03 INFO - Return code: 0 03:48:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:48:03 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')]} 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:03 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')]} 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:48:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 03:48:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 03:48:03 INFO - Using env: {'DISPLAY': ':0', 03:48:03 INFO - 'HOME': '/home/cltbld', 03:48:03 INFO - 'LANG': 'en_US.UTF-8', 03:48:03 INFO - 'LANGUAGE': 'en_US:en', 03:48:03 INFO - 'LOGNAME': 'cltbld', 03:48:03 INFO - 'MAIL': '/var/mail/cltbld', 03:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:03 INFO - 'MOZ_NO_REMOTE': '1', 03:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:03 INFO - 'NO_EM_RESTART': '1', 03:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:48:03 INFO - 'PWD': '/builds/slave/test-pgo', 03:48:03 INFO - 'SHELL': '/bin/bash', 03:48:03 INFO - 'SHLVL': '1', 03:48:03 INFO - 'TERM': 'linux', 03:48:03 INFO - 'TMOUT': '86400', 03:48:03 INFO - 'USER': 'cltbld', 03:48:03 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:03 INFO - '_': '/tools/buildbot/bin/python'} 03:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:48:04 INFO - Running setup.py (path:/tmp/pip-gSyFAb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:48:04 INFO - Running setup.py (path:/tmp/pip-C1gsYK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:48:04 INFO - Running setup.py (path:/tmp/pip-wqbiUa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:48:04 INFO - Running setup.py (path:/tmp/pip-CiuZJQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:48:04 INFO - Running setup.py (path:/tmp/pip-Ec1n3Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:48:04 INFO - Running setup.py (path:/tmp/pip-X008hi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:48:04 INFO - Running setup.py (path:/tmp/pip-G5JKCb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:48:04 INFO - Running setup.py (path:/tmp/pip-4UPKc8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:48:04 INFO - Running setup.py (path:/tmp/pip-_z6KY9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:48:04 INFO - Running setup.py (path:/tmp/pip-nQEkXZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:48:04 INFO - Running setup.py (path:/tmp/pip-tlfxhN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:48:04 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)) 03:48:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:48:04 INFO - Running setup.py (path:/tmp/pip-sxx5BV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:48:05 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)) 03:48:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:48:05 INFO - Running setup.py (path:/tmp/pip-tP54hO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:48:05 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)) 03:48:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:48:05 INFO - Running setup.py (path:/tmp/pip-IhvYcy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:48:05 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)) 03:48:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:48:05 INFO - Running setup.py (path:/tmp/pip-qnBF9Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:48:05 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)) 03:48:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:48:05 INFO - Running setup.py (path:/tmp/pip-fnWmWO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:48:05 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)) 03:48:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:48:05 INFO - Running setup.py (path:/tmp/pip-TbjA7E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:48:05 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)) 03:48:05 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)) 03:48:05 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)) 03:48:05 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)) 03:48:05 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)) 03:48:05 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)) 03:48:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:07 INFO - Downloading blessings-1.6.tar.gz 03:48:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:48:07 INFO - Installing collected packages: blessings 03:48:07 INFO - Running setup.py install for blessings 03:48:07 INFO - Successfully installed blessings 03:48:07 INFO - Cleaning up... 03:48:07 INFO - Return code: 0 03:48:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:48:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:48:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:48:07 INFO - Reading from file tmpfile_stdout 03:48:07 INFO - Current package versions: 03:48:07 INFO - argparse == 1.2.1 03:48:07 INFO - blessings == 1.6 03:48:07 INFO - blobuploader == 1.2.4 03:48:07 INFO - docopt == 0.6.1 03:48:07 INFO - manifestparser == 1.1 03:48:07 INFO - mozInstall == 1.12 03:48:07 INFO - mozcrash == 0.16 03:48:07 INFO - mozdebug == 0.1 03:48:07 INFO - mozdevice == 0.47 03:48:07 INFO - mozfile == 1.2 03:48:07 INFO - mozhttpd == 0.7 03:48:07 INFO - mozinfo == 0.9 03:48:07 INFO - mozleak == 0.1 03:48:07 INFO - mozlog == 3.1 03:48:07 INFO - moznetwork == 0.27 03:48:07 INFO - mozprocess == 0.22 03:48:07 INFO - mozprofile == 0.27 03:48:07 INFO - mozrunner == 6.11 03:48:07 INFO - mozscreenshot == 0.1 03:48:07 INFO - mozsystemmonitor == 0.0 03:48:07 INFO - moztest == 0.7 03:48:07 INFO - mozversion == 1.4 03:48:07 INFO - psutil == 3.1.1 03:48:07 INFO - requests == 1.2.3 03:48:07 INFO - wsgiref == 0.1.2 03:48:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:48:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 03:48:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 03:48:07 INFO - Using env: {'DISPLAY': ':0', 03:48:07 INFO - 'HOME': '/home/cltbld', 03:48:07 INFO - 'LANG': 'en_US.UTF-8', 03:48:07 INFO - 'LANGUAGE': 'en_US:en', 03:48:07 INFO - 'LOGNAME': 'cltbld', 03:48:07 INFO - 'MAIL': '/var/mail/cltbld', 03:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:07 INFO - 'MOZ_NO_REMOTE': '1', 03:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:07 INFO - 'NO_EM_RESTART': '1', 03:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:48:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:48:07 INFO - 'SHELL': '/bin/bash', 03:48:07 INFO - 'SHLVL': '1', 03:48:07 INFO - 'TERM': 'linux', 03:48:07 INFO - 'TMOUT': '86400', 03:48:07 INFO - 'USER': 'cltbld', 03:48:07 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:07 INFO - '_': '/tools/buildbot/bin/python'} 03:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 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)) 03:48:07 INFO - Cleaning up... 03:48:07 INFO - Return code: 0 03:48:07 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:48:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 03:48:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:48:07 INFO - Using env: {'DISPLAY': ':0', 03:48:07 INFO - 'HOME': '/home/cltbld', 03:48:07 INFO - 'LANG': 'en_US.UTF-8', 03:48:07 INFO - 'LANGUAGE': 'en_US:en', 03:48:07 INFO - 'LOGNAME': 'cltbld', 03:48:07 INFO - 'MAIL': '/var/mail/cltbld', 03:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:07 INFO - 'MOZ_NO_REMOTE': '1', 03:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:07 INFO - 'NO_EM_RESTART': '1', 03:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:48:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:48:07 INFO - 'SHELL': '/bin/bash', 03:48:07 INFO - 'SHLVL': '1', 03:48:07 INFO - 'TERM': 'linux', 03:48:07 INFO - 'TMOUT': '86400', 03:48:07 INFO - 'USER': 'cltbld', 03:48:07 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:07 INFO - '_': '/tools/buildbot/bin/python'} 03:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:08 INFO - Downloading/unpacking jsonschema 03:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:48:09 INFO - Downloading/unpacking functools32 (from jsonschema) 03:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 03:48:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:48:10 INFO - warning: no files found matching '*.txt' 03:48:10 INFO - no previously-included directories found matching 'build' 03:48:10 INFO - no previously-included directories found matching 'dist' 03:48:10 INFO - no previously-included directories found matching '.git*' 03:48:10 INFO - Installing collected packages: jsonschema, functools32 03:48:10 INFO - Running setup.py install for functools32 03:48:10 INFO - warning: no files found matching '*.txt' 03:48:10 INFO - no previously-included directories found matching 'build' 03:48:10 INFO - no previously-included directories found matching 'dist' 03:48:10 INFO - no previously-included directories found matching '.git*' 03:48:10 INFO - Successfully installed jsonschema functools32 03:48:10 INFO - Cleaning up... 03:48:10 INFO - Return code: 0 03:48:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 03:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1370233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e3c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', '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 03:48:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 03:48:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:48:10 INFO - Using env: {'DISPLAY': ':0', 03:48:10 INFO - 'HOME': '/home/cltbld', 03:48:10 INFO - 'LANG': 'en_US.UTF-8', 03:48:10 INFO - 'LANGUAGE': 'en_US:en', 03:48:10 INFO - 'LOGNAME': 'cltbld', 03:48:10 INFO - 'MAIL': '/var/mail/cltbld', 03:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:10 INFO - 'MOZ_NO_REMOTE': '1', 03:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:10 INFO - 'NO_EM_RESTART': '1', 03:48:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:48:10 INFO - 'PWD': '/builds/slave/test-pgo', 03:48:10 INFO - 'SHELL': '/bin/bash', 03:48:10 INFO - 'SHLVL': '1', 03:48:10 INFO - 'TERM': 'linux', 03:48:10 INFO - 'TMOUT': '86400', 03:48:10 INFO - 'USER': 'cltbld', 03:48:10 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:10 INFO - '_': '/tools/buildbot/bin/python'} 03:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:10 INFO - Downloading/unpacking flake8 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:48:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:48:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48: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 03:48:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 03:48:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:48:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:48:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:48:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:48:12 INFO - no previously-included directories found matching 'docs/_build' 03:48:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:48:12 INFO - Running setup.py install for pep8 03:48:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:48:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:48:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:48:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:48:13 INFO - no previously-included directories found matching 'docs/_build' 03:48:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 03:48:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:48:13 INFO - Cleaning up... 03:48:13 INFO - Return code: 0 03:48:13 INFO - Running post-action listener: _resource_record_post_action 03:48:13 INFO - Running post-action listener: _start_resource_monitoring 03:48:13 INFO - Starting resource monitoring. 03:48:13 INFO - ##### 03:48:13 INFO - ##### Running install step. 03:48:13 INFO - ##### 03:48:13 INFO - Running pre-action listener: _resource_record_pre_action 03:48:13 INFO - Running main action method: install 03:48:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:48:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:48:13 INFO - Reading from file tmpfile_stdout 03:48:13 INFO - Detecting whether we're running mozinstall >=1.0... 03:48:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:48:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:48:13 INFO - Reading from file tmpfile_stdout 03:48:13 INFO - Output received: 03:48:13 INFO - Usage: mozinstall [options] installer 03:48:13 INFO - Options: 03:48:13 INFO - -h, --help show this help message and exit 03:48:13 INFO - -d DEST, --destination=DEST 03:48:13 INFO - Directory to install application into. [default: 03:48:13 INFO - "/builds/slave/test-pgo"] 03:48:13 INFO - --app=APP Application being installed. [default: firefox] 03:48:13 INFO - mkdir: /builds/slave/test-pgo/build/application 03:48:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 03:48:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 03:48:28 INFO - Reading from file tmpfile_stdout 03:48:28 INFO - Output received: 03:48:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:48:28 INFO - Running post-action listener: _resource_record_post_action 03:48:28 INFO - ##### 03:48:28 INFO - ##### Running run-tests step. 03:48:28 INFO - ##### 03:48:28 INFO - Running pre-action listener: _resource_record_pre_action 03:48:28 INFO - Running main action method: run_tests 03:48:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:48:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:48:28 INFO - Python 2.7.3 03:48:28 INFO - Return code: 0 03:48:28 INFO - grabbing minidump binary from tooltool 03:48:28 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')]} 03:48:28 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 0x1ecb350>, '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 0x1ecc250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ecc6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:48:28 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 03:48:28 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 03:48:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:48:28 INFO - Return code: 0 03:48:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:48:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:48:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:48:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:48:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:48:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 03:48:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 03:48:29 INFO - Return code: 0 03:48:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 03:48:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost 03:48:29 INFO - Using env: {'DISPLAY': ':0', 03:48:29 INFO - 'HOME': '/home/cltbld', 03:48:29 INFO - 'LANG': 'en_US.UTF-8', 03:48:29 INFO - 'LANGUAGE': 'en_US:en', 03:48:29 INFO - 'LOGNAME': 'cltbld', 03:48:29 INFO - 'MAIL': '/var/mail/cltbld', 03:48:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:48:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:29 INFO - 'MOZ_NO_REMOTE': '1', 03:48:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:48:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:29 INFO - 'NO_EM_RESTART': '1', 03:48:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:48:29 INFO - 'PWD': '/builds/slave/test-pgo', 03:48:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:48:29 INFO - 'SHELL': '/bin/bash', 03:48:29 INFO - 'SHLVL': '1', 03:48:29 INFO - 'TERM': 'linux', 03:48:29 INFO - 'TMOUT': '86400', 03:48:29 INFO - 'USER': 'cltbld', 03:48:29 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886', 03:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:29 INFO - '_': '/tools/buildbot/bin/python'} 03:48:29 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost'] with output_timeout 3600 03:48:29 INFO - mozversion INFO | application_buildid: 20160125001712 03:48:29 INFO - mozversion INFO | application_changeset: ab89c814f1b8a841168de98ac2679a1d66122a06 03:48:29 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 03:48:29 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:48:29 INFO - mozversion INFO | application_name: Firefox 03:48:29 INFO - mozversion INFO | application_remotingname: firefox-dev 03:48:29 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:48:29 INFO - mozversion INFO | application_vendor: Mozilla 03:48:29 INFO - mozversion INFO | application_version: 45.0a2 03:48:29 INFO - mozversion INFO | platform_buildid: 20160125001712 03:48:29 INFO - mozversion INFO | platform_changeset: ab89c814f1b8a841168de98ac2679a1d66122a06 03:48:29 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:48:29 INFO - mozversion INFO | platform_version: 45.0a2 03:48:29 INFO - 2016-01-25 03:48:29,388 DEBUG : using testdate: 1453722509 03:48:29 INFO - 2016-01-25 03:48:29,388 DEBUG : actual date: 1453722509 03:48:29 INFO - 2016-01-25 03:48:29,397 INFO : starting webserver on 'localhost:54299' 03:48:29 INFO - 2016-01-25 03:48:29,398 INFO : Starting test suite talos-linux64-ix-033 03:48:29 INFO - 2016-01-25 03:48:29,398 INFO : Starting test dromaeo_css 03:48:29 INFO - 2016-01-25 03:48:29,398 DEBUG : operating with platform_type : linux_ 03:48:29 INFO - 2016-01-25 03:48:29,399 INFO : Initialising browser for dromaeo_css test... 03:48:29 INFO - 2016-01-25 03:48:29,414 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1IksxX/profile http://localhost:54299/getInfo.html 03:48:34 INFO - 2016-01-25 03:48:34,005 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:48:34 INFO - 2016-01-25 03:48:34,005 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:48:34 INFO - 2016-01-25 03:48:34,005 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:48:34 INFO - 2016-01-25 03:48:34,005 DEBUG : BROWSER_OUTPUT: __metrics 03:48:34 INFO - 2016-01-25 03:48:34,182 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] 03:48:35 INFO - 2016-01-25 03:48:35,684 INFO : Browser initialized. 03:48:35 INFO - 2016-01-25 03:48:35,685 INFO : Running cycle 1/1 for dromaeo_css test... 03:48:35 INFO - 2016-01-25 03:48:35,685 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1IksxX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:48:47 INFO - 2016-01-25 03:48:47,500 DEBUG : BROWSER_OUTPUT: RSS: Main: 138260480 03:48:47 INFO - 2016-01-25 03:48:47,501 DEBUG : BROWSER_OUTPUT: 03:49:38 INFO - 2016-01-25 03:49:38,593 DEBUG : BROWSER_OUTPUT: 1453722578592 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] 03:51:47 INFO - 2016-01-25 03:51:47,514 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54299/tests/dromaeo/cssquery-ext.html) 03:51:48 INFO - 2016-01-25 03:51:48,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 232169472 03:51:48 INFO - 2016-01-25 03:51:48,499 DEBUG : BROWSER_OUTPUT: 03:54:50 INFO - 2016-01-25 03:54:50,752 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/dromaeo/cssquery-ext.html (next: http://localhost:54299/tests/dromaeo/cssquery-jquery.html) 03:54:51 INFO - 2016-01-25 03:54:51,318 DEBUG : BROWSER_OUTPUT: RSS: Main: 219557888 03:54:51 INFO - 2016-01-25 03:54:51,318 DEBUG : BROWSER_OUTPUT: 03:57:53 INFO - 2016-01-25 03:57:53,161 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54299/tests/dromaeo/cssquery-mootools.html) 03:57:53 INFO - 2016-01-25 03:57:53,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 220221440 03:57:53 INFO - 2016-01-25 03:57:53,723 DEBUG : BROWSER_OUTPUT: 04:00:55 INFO - 2016-01-25 04:00:55,484 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54299/tests/dromaeo/cssquery-prototype.html) 04:00:56 INFO - 2016-01-25 04:00:56,058 DEBUG : BROWSER_OUTPUT: RSS: Main: 202035200 04:00:56 INFO - 2016-01-25 04:00:56,058 DEBUG : BROWSER_OUTPUT: 04:03:58 INFO - 2016-01-25 04:03:58,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54299/tests/dromaeo/cssquery-yui.html) 04:03:59 INFO - 2016-01-25 04:03:59,004 DEBUG : BROWSER_OUTPUT: RSS: Main: 205426688 04:03:59 INFO - 2016-01-25 04:03:59,004 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,202 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/dromaeo/cssquery-yui.html (next: http://localhost:54299/tests/dromaeo/cssquery-dojo.html) 04:07:01 INFO - 2016-01-25 04:07:01,452 DEBUG : BROWSER_OUTPUT: RSS: Main: 245260288 04:07:01 INFO - 2016-01-25 04:07:01,452 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,452 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:07:01 INFO - 2016-01-25 04:07:01,453 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:07:01 INFO - 2016-01-25 04:07:01,453 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:07:01 INFO - 2016-01-25 04:07:01,453 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:07:01 INFO - 2016-01-25 04:07:01,454 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3301.323228738109;3347.7656564040526;3375.4768010958296;3379.3579219948224;3386.8137252451106;5502.669839809614;5521.033819081655;5523;5525.088360420531;5547.58393120516;5498.780048798043;5536.972315138429;5548.55611551076;5558.805441809537;5562.554995600352;4913.115639184945;4994.051130285234;5031;5060.645754797148;5123.333966584339;5043.293938848564;5068.315777370045;5070.797168113271;5089.3638295213095;5097.413797413308;4933.753312334369;5003;5028.421731500869;5053.67151135175;5124.795008199668;5037.042961837257;5115.872103197413;5117.53942145205;5118.232265160219;5142.228665700138;4703.553162449562;5017.7491125443585;5020.974895125538;5045.596352291808;5080.085584594772;5236.29310043147;5258.342707161605;5304.973475132639;5306;5313.920291195635;5777.884442311172;5798.478136967693;5828.9799285125355;5837;5843.912341314884;5757;5848.093545500455;5868.882622347529;5873.911891321633;5929.407059294079;5403.189521571757;5441.83674489762;5442.673439593637;5468.56251499887;5473.589480788957;5975.223220981245;6037.426444487766;6060.090986352083;6061.757529698851;6071;5540.141278101901;5708;5767.394423585547;5864.38423965486;5872.148538461899;5880.705964701833;5984.910226346608;5992.850178745566;5997.730102145414;6000.03999360109;5897;5932.614380065368;5958.880822383528;5961.4038596140035;5963.344032156459;5622.775088996476;5739.311282646026;5926.525877929756;5937.554683398763;6070.392960703983;4576.816927322936;4579.267317229227;4587.655925805511;4602.447706275201;4613.100445413179;4102.794860256975;4103.733257338264;4103.774292413946;4117.5882411759185;4134.586541345841;5437.700926449083;5474.206240095244;5536.390996990328;5563.554915606742;5646.378898321182;5815.563832712563;5818.883622327615;5820.3888591698105;5835.912461313083;5872.677002764889;5457.7271136443815;5469.234307196995;5479.945200547943;5480.287562616834;5487.6158668892795;4930.482299358516;5072.721000344943;5161.974190129101;5269.104252277122;5296.285001524822;6167.414095660905;6188.8762224755255;6190.907136392958;6199.380061993765;6200.441960223601;5667.829965101054;6012.549058820518;6022.337542870193;6035.637861728311;6036.185115009594;5953.374895635896;6001.90997135052;6092.546648701599;6101.317092345618;6107.341762062797;5762.250907382014;5804.245448091721;5807.912881306699;5817.447342502455;5850.6782126981725;5583.8883222336135;5693.629914055573;5871.647701137946;5875.50058245054;5894.498967587631;5730.169125476866;5799.594028417971;5801.129830525455;5805.187273781758;5832.008558544972;5753;5767.336756273083;5771.394003629726;5772.5959182856795;5773.336066352424;5447.12845944647;5543.029969755196;5609.80365687199;5637.5489960802215;5677.886442271214;5800.361960184461;5846.41535846412;5857.297124345106;5863.1791549184;5897.439743224387;5666.801661941812;5681.261436013292;5710.942890571041;5720.227769251098;5755.8561035974435 04:07:01 INFO - 2016-01-25 04:07:01,455 DEBUG : BROWSER_OUTPUT: |1;ext.html;591.742591972492;608.6378604730183;629.3203340392377;636.0491065856544;688.0058315733767;31500;32186.195345116383;39577.20845583086;40128.197436051334;40171.59828401715;35795.463068054;36215;36302.45546316807;36323.45514817273;36337.27325453496;8066.838663226732;8923.375363724526;9075.455472671627;9103.590338434753;9106.18044376006;8182.140875208107;8271.710490132817;8284.627191776355;10478.31890927091;10726.03465688088;10558.788824223511;10643.84034239487;10652.573897044109;10660.253782235246;10663.413512256822;10547.789044219111;10616.150707943423;10647.361158330525;10649.201309901788;10650.946745266301;8293.502389856567;8523.105073967268;8527.18991695788;10657.626983055556;10922.453877306183;44224.33663495018;44315;44402;44425.55574444246;44645.107097858185;3548.929021419583;3704.3887758519813;3707.5736290326417;3874.728768986172;4003.719739618228;33752;33863.83068084669;34314.48528272078;34375.656243437494;35181.824090879636;4147.834086636531;4212.304969679999;4240.936385954213;4260.403543503912;4272.581934452457;276608;280822;281281;281831;282158;62431.063534047025;70372;71138.2886171142;71142.2885771146;71199.64400178069;59431;82933.17066829254;83775.23011464377;84309.57845210735;84507.57746211352;16372.672546549002;16476.42332518356;16592.17039148062;16597.91701041487;16598.087105209328;12952.222866627844;13092.541761038317;13093.214407135602;13099.607011789662;13110.868891311156;23203.303900883002;23291.417714557272;23319.300420987398;23319.650205246933;23505.882470587538;8971.820563588692;9231.492267925329;9237.722868313962;9354;9357;1005.301315585661;1045.3048722599385;1123.8707548632012;1137.9871913996549;1246.0592252849042;29792.404151917275;30682.53976190359;30887.536686949712;31411.37177256442;31824.681753182635;1083.2904447586843;1114.5486078138367;1116.084810455435;1200.3338147328143;1202.9879701203051;14740.484083057256;15017.324220410108;15674.373025079098;17519.299228030737;17740.822591774177;423019;425413;427712;427751;427855;58878.411215887296;60529.69735151384;61142;61156.38843611507;61273.38726612766;1343.1001229176475;1419.1059632431502;1428.4928850258216;1433.5341014170435;1445.06793118438;29795.404091918474;30101.548476772427;30121.548176776927;31073.378532429677;31192.844035779675;44782;45224.32163517484;47884.28173577469;48917;54106.45893541014;214916;214972;215594;215890;215935;33122.17194570155;33358.33283334368;33566.328673426884;33611.49582756212;33625.327493449506;46688;48075;48177.27734083922;48468.5153148464;48677.26984095312;38726.22547549089;39287.01782455461;39517;39533.40699889562;40660.1867962645;39172.2165556693;40153.99615009648;40306.79846600866;40497.7902991407;40611;39429.408558871066;39638.60361396349;40436.59563404328;40472.79763601163;40475.59524404718;31673;32822.835885820416;33010.66989330076;33038.33923321568;33111.66888331086;27025.729742702322;27106;27240.86379568169;27318.726812731617;27320.726792731817 04:07:01 INFO - 2016-01-25 04:07:01,457 DEBUG : BROWSER_OUTPUT: |2;jquery.html;379.02968400893695;380.51294343240664;386.7621412831108;388.69099066242336;389.9083715326898;14959.700805983874;16707.415240466587;17344.56638584036;19875.409967202606;19885.105170267336;16889.408870689527;17322.480325590222;17326.133693315347;18647.25410983559;19108.522286942833;5113.642045056848;5230;5237.842864714045;5243.292155559008;5267.52592266696;3952.1898010907694;4041.8383264669524;4042.8584999525024;4046.4941882264716;5155.793768249266;5500.477454641803;5687.744051517691;5702.65784052956;5714.285714285715;5716.1711551824965;5514.145307477347;6196.907046394308;6198.039303907902;6200.844978875519;6205.658688772116;5426.240326354314;5464.180372944051;5466.972665136649;5484.2596249506205;5519.779208831682;12030.759384812342;12104.757904841854;12191;12229.510819567207;12802.103852730312;2240.3950933247934;2242.843000989931;2247.6628505724107;2249.224017713886;2307.3193407944527;9899;10579.31234469757;13167.078304518687;13253.3373331334;13559.661008474866;1948.7589272336927;1976.9604607907904;1994.481434826941;2068.3588087692865;2093.9790602093935;61123.69438152781;64564.3543564563;65279.34720652828;65721.342786572;65896.01155982712;10757.892421075689;10923.617673381394;11036.834447483294;11041.558337666564;11115.777684446266;14545.92727036358;14549.563513094625;14568.490102846561;14681;14713.411463541552;7902.565358905201;7928.286454219148;7988.84022319562;7994.40041996841;7997.6800927963395;7512.699492020367;7519.323260906544;7520.661570229353;7574.5076570022775;7575;4638.5593368629925;4712.387389639325;4745.383100196952;4812.06164797868;4852.490488498727;1792.8924264544169;1815.6731788277973;1827.6436094961355;1831.3415919349525;1831.8970875620962;451.6071018214175;963.210167662524;972.9854052189223;1060.5174645536263;1136.3295655563256;9706;10721.624743133953;10891;10899.400532970763;10973.615923442801;475.04752970295027;481.4295060353492;485.83481616250646;487.05025200858466;488.5627363509639;6296.590721603081;6423.229212494531;6474.255460621995;6496.967515162394;10109.39343639384;612352;615831;624499;669856;673222;82569;85207.14792851992;85901.57049214838;86139;87938;1221.590767093016;1226.607485604598;1266.2465832829453;1275.1647670775658;1295.8963282937343;16278.348866045699;16474;16568.17159142062;16609.501714948572;16625.584360391087;37666.811665942594;38062.80968595139;38497.807510962266;38709.2258154841;38858.80570597129;49927.251091234386;50391.74804125956;50959.23561146512;51364.74317628388;51751.2237316433;9530.380525265835;9621.56702948383;9718.173955213602;9731.318807683394;9740.902590974;15396.84603153954;17405.82594174042;17956;18103.547411314823;18994.905025474785;14940.626484337978;15211.923940380228;15274.236288185768;15336;15613.297401617181;14298.85701143017;14589.270536473347;14691.485797997018;15252.618684532976;15705.450309239122;13169.934150329187;13484.595462136152;14201.502947396792;15222;15586;9934.155596774355;10025.398476091457;10115.342502737298;10144.847827282745;10152.898471015194;13360.331983400985;14410.351534180778;15202;15515.84484155144;16022.439214627431 04:07:01 INFO - 2016-01-25 04:07:01,460 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1523.7028779388024;1530.3572499550187;1612.3953517430964;1776.2539733312008;1819.6997495413254;6446.742130314788;7422.072240969879;7538.434617403689;7552.244775522445;7564.357029652486;7405.1854296027395;7601.20187380324;7609.391248700104;7616.847663046736;7617.8476430471355;1980.3365872432753;2005.9899700501478;2006.9197232110698;2258.7289525256992;2289.5077558324956;2220.6335954567476;2288.794008539231;2291.7708229177115;2292.083166733303;2293.5068960173558;2220.888955552224;2239.932802015934;2282.600544904635;2283.954320913581;2286.2341115726254;2256.187772401934;2263.569921714869;2264.682944387787;2280.2361208995007;2291.633338665814;2277.1119263487253;2286.2683941138844;2316.143027079991;2321.3268152235833;2323.59337116004;7163.606001669906;7234.421246300283;7240;7240.782776516713;7250.383717383976;4439.711418757771;4500.437445319335;4506.098780243931;4527;4540.636749060067;3026.7729920255847;3038.3315670552665;3085.059056987618;3089.830059346735;3120.7971481853606;997.481309718942;1002.72425083102;1006.1900170362856;1014.6144465103288;1052.4106500359746;10180.236482263786;10240.436775977318;10249.795004099951;10277.17782577392;10286.074253317238;3981.0843505993203;3982.422548730418;4055.270051390719;4065.6137666921595;4067.8576249831704;8920.152585504367;8938.061503542165;8950.104989501127;8952.507612081397;8963.417377870417;4527.162474942146;4541.84103556374;4567.954320456819;4569.1547063793305;4576.565226303496;4160.313548264563;4196.706230563892;4198.958010419918;4207.621314081686;4225.492940847118;3242.854071566785;3381.4420620597816;3390.9152271193416;3630.0380399194237;3704.1850792825535;1169.8479197704244;1191.243560339191;1193.4390836306923;1195.4261954262004;1208.6796998795326;1115.726646971497;1115.9888401116048;1141.001623579368;1275.3177050278057;1348.5077946549438;7080.822979425452;7094.92905070953;7161.427085833121;7165.6417179140835;7173.175084865203;1095.27164435651;1111.9436535291507;1126.9688235264641;1127.6166103524677;1154.7690461907484;5450.04624190765;5499.285092937893;5503.642263252876;5506.421825708243;5509.752061157257;8672.8699069514;8789.560521973875;8950.14973577509;8974.37179397449;9115.954420227989;8871.467711937305;8980.057094005035;8985.550722463851;8998.055204203596;9001.909980900238;7067.2579379164445;7190.06529151207;7204.855902881913;7253.746118885813;7253.782386528413;7076.716931322895;7153.320434558708;7155.391791697556;7163.462740294603;7168.462365322517;8142.8778568322705;8456.196661317164;8460.873086903825;8466.872996905175;8479.321654267767;8444.957775211085;8488.363372746946;8496.150384961455;8500.192481714228;8672.132786721277;6732.798016059526;6734.259231484631;6814.591124532544;6821.522493425413;6831.111955445959;6205.906911396423;6322;7243.674034668347;7369.8157546061775;7427.70289188448;6902.24075351721;6931.237563868071;7003.3346832050875;7056.6471676417;7080.504364694422;6920.4809639276245;6956.0609317741455;6996.545224560387;7065.717371305091;7073.363397294268;7016.368526832405;7038;7064.540804847668;7066.081409416743;7078.787636370917;6305.1172835803945;6355.491560675043;6357.3006969234275;6418.935810641834;6426.068220108152;6468.5472016958365;6494.480441564758;6500.609963402211;6508;6508.642024688687 04:07:01 INFO - 2016-01-25 04:07:01,462 DEBUG : BROWSER_OUTPUT: |4;prototype.html;355.2699203137553;355.48277256591655;355.5378008588834;355.63725000499477;357.8711663801031;5979.7907073252445;6124.877502449948;6160.383961603843;6170.907436388457;6214.782482613109;5958;6086.452219300262;6106.633601983873;6122.663253521055;6131.632102073879;4749.097671442432;4750.049990001996;4752.12085764133;4753.85738427846;4754.191787396151;4513.894095946498;4635.745034023127;4647.32613771003;4712.976435117819;4735.360726301939;4681.578657920786;4692.4369075710965;4697.718136911779;4709.835155769549;4729.716217026972;4675.158471475133;4680.789364478606;4723.740194289313;4726.054789042205;4748.7890587899865;4196;4197.874063778091;4205.852795152155;4206.348016057516;4207.579242075768;5734.053881109612;5848.649081055108;5849.678267695275;5851.824445266649;5871.765129394819;5512.531434828044;5547.528460080898;5551.361593416728;5552.611317207797;5560.304961879765;6484.092227088211;6489.935100648981;6498.902516462257;6501.31736167705;6507.3492650735025;4542.818287268506;4679.812807487697;4683.461401938786;4690.1557719610455;4705.200115980291;6497.252815926182;6608.801735947929;6672.566283191577;6786.72853085876;6802.115724955774;4814.374131362901;4822.734749588806;4823.421189457217;4832.734199619054;4840.685355451885;4883.072216278895;4893.192623217201;4895.804167833318;4896.657233993657;4899.853004409873;5482.643628164156;5526.281583394133;5564.4991950724625;5565.916511252334;5569.777208911598;6538.346165383424;6569.915963866004;6575.80272591823;6584.242812076577;6595.604263744191;1699.0740046674591;1719.5701074731317;1721.5179749670112;1722.922468488921;1725.8533024693043;1179.4869231884159;1180.9055275577932;1182.869884312725;1193.6777070191174;1219.615821016377;6293.370662933761;6294.653794041372;6295.527835412362;6296.118543403927;6303.621782693053;6801.761938332134;6806.115205023315;6812.216595091628;6817.386435220854;6817.931820681829;6256.843578910563;6331.746730130743;6372.330905254881;6387.712552935129;6392.105105285264;3691.7600355976774;3715.5169827922737;3718.1076541629814;3719.962800372016;3726.4969229153717;7037.683304251321;7062;7069.363757261871;7072.611006394594;7083.220845706968;6936.618485983319;7024.297570242935;7055.8236044098285;7060.964695176492;7062.71749130029;6990.61551614666;7020.5787652741;7029.191642961023;7037.120359955005;7038.507304488637;6831.419329357061;6874.9312506876295;6877.828054298682;6890.414314783299;6891.620960847202;6677.165354330708;6775.593464392152;6779.15260592426;6792.728290868507;6805.1833779944745;6997.05539752127;7030.121234845645;7036.5074444790425;7041.683124259318;7044.647767611701;4590.219662657423;4619;4621.884452888705;4630.907381852277;4632.420947381577;6765.357291057342;6906.06768086322;6909.239983601699;6911.516193866582;6912.965435172792;6704.932950670431;6707.295733947865;6708.22855371619;6714.83212919681;6718.193816742022;6695.698693558704;6701.865962680621;6702.430293425114;6710.664466776544;6712.160979877515;6439.098526206429;6549.4760419165395;6586.209654841449;6588.077669126422;6594.4724422045165;6715.3620406061345;6718.361755633207;6721.831954201184;6724.29394913527;6728.125343705483;6568.638724870178;6573.5069869759;6575.079488871657;6586.341365863567;6586.70359833799 04:07:01 INFO - 2016-01-25 04:07:01,463 DEBUG : BROWSER_OUTPUT: |5;yui.html;916.4455504419826;917.2157805076663;926.6525053105086;927.5176908007836;942.3544871762839;14590.416383344667;15082.924585377072;15467.381304747796;15480.999999999985;15484;14576.271186440663;14588.708225835477;14666;14695.265236738147;14701.485448009322;770.2374649097382;783.2402569507568;902.025812122909;903.9457632542036;926.2173463423396;905.4069584422214;911.4030310146865;916.0107084348887;916.2532535983153;916.5279880861361;785.5286827903269;934.9111834375724;947.4741518457254;947.9952600237024;948.5209968965697;785.2657764989724;929.7257309093834;945.1588086602928;948.4451595816456;949.093615597103;696.3350000749273;699.8600279944031;701.5650296816007;702.5293053654013;702.7259368846154;14212.147271163763;14331.211783351911;14354.64113397163;14385.496507622287;14388.712225755531;12736.872631273754;13142.802857957138;13331.800022999663;13358.332083395791;13859;11297.096232301396;11314.490847911864;11452.484638191301;11593.536258549648;11719.531218751239;3857.2092720992127;3861.903452413684;3867.284552357795;3869.1681288523105;3874.6900247980093;49919.25121123186;50540;50998.7450062751;51461.2280815788;51596.22605660881;10762.569497220024;10801;10884.564617415374;10932.945335273273;10950.507227174796;19392.8060719391;19656.21375145007;19663.016849157484;19693.40919772409;19710.70433943492;8376.874346884802;8421.368397370221;8458.069612342637;8685.261752750961;8698.869516957251;4771.475137734847;4841.298011788342;4849.393825771778;4887.877803054952;4987.775550100254;3262.8205448700073;3283.129970557804;3284.441644920369;3288.4245257079915;3288.490284005983;608.4797498139116;609.5276160975279;610.1793088296234;617.4350469320522;630.426312056036;618.9226346706662;680.6392611915594;695.3463744080548;699.8285420072115;718.9532680375759;8994.550272486349;9210.585523651453;9288.767780805534;9300.441973481613;9757.414555126716;713.8970290900571;737.0896942276285;740.8814589665627;742.279988411251;749.1759065028534;4128.112455821953;4246.235677578004;4275.251830929575;4315.07225946423;4318.827246910151;59697.40302597005;59769.40230597725;62242;62761.37238627555;62887.685561571896;55026.44973550293;55662.44337556572;55814.72092639511;56142;56166.15750763742;49305;49527.75236123797;49688.75155624199;50454.243186352236;50493.999999999265;8316.501009939422;8644.09237030128;8650.653973841228;9113.134252246025;9130.589123489326;36810.63189368179;37231.81384093062;38827.417588735625;38864.22271554609;39303.213935721695;47885.76057119692;47889;48018.27972580484;48328.758356207996;49289.75355123202;11049.558017679203;11102.777944440904;11644.6506604802;11940.223885447418;12502.562410315595;13460.528881489101;13728.519501817389;13795.862041379458;14137;14205.857941420454;11842.703932401759;11956.820647690118;12124.298524154832;12267.75464490723;12868.806967895302;11894.524219031488;12107.152499324962;12110.45502952387;12121;12458.501659933501;10831.2959657622;11136.276142050741;11286.20996530235;11459;11496.367699776592;9858.753531161778;9946.950265248628;9981.74982298315;10208.54061567246;10622.681319560426;10078.697639070839;10302.896971030494;10363.896361036293;10385.792284154426;10404.219683523615 04:07:01 INFO - 2016-01-25 04:07:01,464 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:07:01 INFO - 2016-01-25 04:07:01,464 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:07:01 INFO - 2016-01-25 04:07:01,464 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,765 04:07:01 INFO - 2016-01-25 04:07:01,464 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:07:01 INFO - 2016-01-25 04:07:01,464 DEBUG : BROWSER_OUTPUT: __startTimestamp1453723621451__endTimestamp 04:07:01 INFO - 2016-01-25 04:07:01,464 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,465 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:07:01 INFO - 2016-01-25 04:07:01,465 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:07:01 INFO - 2016-01-25 04:07:01,465 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,465 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5478.16 Median:5666.80 stddev:590.87 (10.4%) stddev-sans-first:567.47 04:07:01 INFO - 2016-01-25 04:07:01,466 DEBUG : BROWSER_OUTPUT: Values: 3301.3 3347.8 3375.5 3379.4 3386.8 5502.7 5521.0 5523.0 5525.1 5547.6 5498.8 5537.0 5548.6 5558.8 5562.6 4913.1 4994.1 5031.0 5060.6 5123.3 5043.3 5068.3 5070.8 5089.4 5097.4 4933.8 5003.0 5028.4 5053.7 5124.8 5037.0 5115.9 5117.5 5118.2 5142.2 4703.6 5017.7 5021.0 5045.6 5080.1 5236.3 5258.3 5305.0 5306.0 5313.9 5777.9 5798.5 5829.0 5837.0 5843.9 5757.0 5848.1 5868.9 5873.9 5929.4 5403.2 5441.8 5442.7 5468.6 5473.6 5975.2 6037.4 6060.1 6061.8 6071.0 5540.1 5708.0 5767.4 5864.4 5872.1 5880.7 5984.9 5992.9 5997.7 6000.0 5897.0 5932.6 5958.9 5961.4 5963.3 5622.8 5739.3 5926.5 5937.6 6070.4 4576.8 4579.3 4587.7 4602.4 4613.1 4102.8 4103.7 4103.8 4117.6 4134.6 5437.7 5474.2 5536.4 5563.6 5646.4 5815.6 5818.9 5820.4 5835.9 5872.7 5457.7 5469.2 5479.9 5480.3 5487.6 4930.5 5072.7 5162.0 5269.1 5296.3 6167.4 6188.9 6190.9 6199.4 6200.4 5667.8 6012.5 6022.3 6035.6 6036.2 5953.4 6001.9 6092.5 6101.3 6107.3 5762.3 5804.2 5807.9 5817.4 5850.7 5583.9 5693.6 5871.6 5875.5 5894.5 5730.2 5799.6 5801.1 5805.2 5832.0 5753.0 5767.3 5771.4 5772.6 5773.3 5447.1 5543.0 5609.8 5637.5 5677.9 5800.4 5846.4 5857.3 5863.2 5897.4 5666.8 5681.3 5710.9 5720.2 5755.9 04:07:01 INFO - 2016-01-25 04:07:01,467 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,467 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49800.79 Median:19365.38 stddev:84290.18 (435.3%) stddev-sans-first:84445.66 04:07:01 INFO - 2016-01-25 04:07:01,467 DEBUG : BROWSER_OUTPUT: Values: 591.7 608.6 629.3 636.0 688.0 31500.0 32186.2 39577.2 40128.2 40171.6 35795.5 36215.0 36302.5 36323.5 36337.3 8066.8 8923.4 9075.5 9103.6 9106.2 8182.1 8271.7 8284.6 10478.3 10726.0 10558.8 10643.8 10652.6 10660.3 10663.4 10547.8 10616.2 10647.4 10649.2 10650.9 8293.5 8523.1 8527.2 10657.6 10922.5 44224.3 44315.0 44402.0 44425.6 44645.1 3548.9 3704.4 3707.6 3874.7 4003.7 33752.0 33863.8 34314.5 34375.7 35181.8 4147.8 4212.3 4240.9 4260.4 4272.6 276608.0 280822.0 281281.0 281831.0 282158.0 62431.1 70372.0 71138.3 71142.3 71199.6 59431.0 82933.2 83775.2 84309.6 84507.6 16372.7 16476.4 16592.2 16597.9 16598.1 12952.2 13092.5 13093.2 13099.6 13110.9 23203.3 23291.4 23319.3 23319.7 23505.9 8971.8 9231.5 9237.7 9354.0 9357.0 1005.3 1045.3 1123.9 1138.0 1246.1 29792.4 30682.5 30887.5 31411.4 31824.7 1083.3 1114.5 1116.1 1200.3 1203.0 14740.5 15017.3 15674.4 17519.3 17740.8 423019.0 425413.0 427712.0 427751.0 427855.0 58878.4 60529.7 61142.0 61156.4 61273.4 1343.1 1419.1 1428.5 1433.5 1445.1 29795.4 30101.5 30121.5 31073.4 31192.8 44782.0 45224.3 47884.3 48917.0 54106.5 214916.0 214972.0 215594.0 215890.0 215935.0 33122.2 33358.3 33566.3 33611.5 33625.3 46688.0 48075.0 48177.3 48468.5 48677.3 38726.2 39287.0 39517.0 39533.4 40660.2 39172.2 40154.0 40306.8 40497.8 40611.0 39429.4 39638.6 40436.6 40472.8 40475.6 31673.0 32822.8 33010.7 33038.3 33111.7 27025.7 27106.0 27240.9 27318.7 27320.7 04:07:01 INFO - 2016-01-25 04:07:01,467 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,467 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31975.56 Median:19022.92 stddev:104557.58 (549.6%) stddev-sans-first:104823.97 04:07:01 INFO - 2016-01-25 04:07:01,467 DEBUG : BROWSER_OUTPUT: Values: 379.0 380.5 386.8 388.7 389.9 14959.7 16707.4 17344.6 19875.4 19885.1 16889.4 17322.5 17326.1 18647.3 19108.5 5113.6 5230.0 5237.8 5243.3 5267.5 3952.2 4041.8 4042.9 4046.5 5155.8 5500.5 5687.7 5702.7 5714.3 5716.2 5514.1 6196.9 6198.0 6200.8 6205.7 5426.2 5464.2 5467.0 5484.3 5519.8 12030.8 12104.8 12191.0 12229.5 12802.1 2240.4 2242.8 2247.7 2249.2 2307.3 9899.0 10579.3 13167.1 13253.3 13559.7 1948.8 1977.0 1994.5 2068.4 2094.0 61123.7 64564.4 65279.3 65721.3 65896.0 10757.9 10923.6 11036.8 11041.6 11115.8 14545.9 14549.6 14568.5 14681.0 14713.4 7902.6 7928.3 7988.8 7994.4 7997.7 7512.7 7519.3 7520.7 7574.5 7575.0 4638.6 4712.4 4745.4 4812.1 4852.5 1792.9 1815.7 1827.6 1831.3 1831.9 451.6 963.2 973.0 1060.5 1136.3 9706.0 10721.6 10891.0 10899.4 10973.6 475.0 481.4 485.8 487.1 488.6 6296.6 6423.2 6474.3 6497.0 10109.4 612352.0 615831.0 624499.0 669856.0 673222.0 82569.0 85207.1 85901.6 86139.0 87938.0 1221.6 1226.6 1266.2 1275.2 1295.9 16278.3 16474.0 16568.2 16609.5 16625.6 37666.8 38062.8 38497.8 38709.2 38858.8 49927.3 50391.7 50959.2 51364.7 51751.2 9530.4 9621.6 9718.2 9731.3 9740.9 15396.8 17405.8 17956.0 18103.5 18994.9 14940.6 15211.9 15274.2 15336.0 15613.3 14298.9 14589.3 14691.5 15252.6 15705.5 13169.9 13484.6 14201.5 15222.0 15586.0 9934.2 10025.4 10115.3 10144.8 10152.9 13360.3 14410.4 15202.0 15515.8 16022.4 04:07:01 INFO - 2016-01-25 04:07:01,468 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,468 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5330.65 Median:6313.56 stddev:2726.31 (43.2%) stddev-sans-first:2718.95 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: Values: 1523.7 1530.4 1612.4 1776.3 1819.7 6446.7 7422.1 7538.4 7552.2 7564.4 7405.2 7601.2 7609.4 7616.8 7617.8 1980.3 2006.0 2006.9 2258.7 2289.5 2220.6 2288.8 2291.8 2292.1 2293.5 2220.9 2239.9 2282.6 2284.0 2286.2 2256.2 2263.6 2264.7 2280.2 2291.6 2277.1 2286.3 2316.1 2321.3 2323.6 7163.6 7234.4 7240.0 7240.8 7250.4 4439.7 4500.4 4506.1 4527.0 4540.6 3026.8 3038.3 3085.1 3089.8 3120.8 997.5 1002.7 1006.2 1014.6 1052.4 10180.2 10240.4 10249.8 10277.2 10286.1 3981.1 3982.4 4055.3 4065.6 4067.9 8920.2 8938.1 8950.1 8952.5 8963.4 4527.2 4541.8 4568.0 4569.2 4576.6 4160.3 4196.7 4199.0 4207.6 4225.5 3242.9 3381.4 3390.9 3630.0 3704.2 1169.8 1191.2 1193.4 1195.4 1208.7 1115.7 1116.0 1141.0 1275.3 1348.5 7080.8 7094.9 7161.4 7165.6 7173.2 1095.3 1111.9 1127.0 1127.6 1154.8 5450.0 5499.3 5503.6 5506.4 5509.8 8672.9 8789.6 8950.1 8974.4 9116.0 8871.5 8980.1 8985.6 8998.1 9001.9 7067.3 7190.1 7204.9 7253.7 7253.8 7076.7 7153.3 7155.4 7163.5 7168.5 8142.9 8456.2 8460.9 8466.9 8479.3 8445.0 8488.4 8496.2 8500.2 8672.1 6732.8 6734.3 6814.6 6821.5 6831.1 6205.9 6322.0 7243.7 7369.8 7427.7 6902.2 6931.2 7003.3 7056.6 7080.5 6920.5 6956.1 6996.5 7065.7 7073.4 7016.4 7038.0 7064.5 7066.1 7078.8 6305.1 6355.5 6357.3 6418.9 6426.1 6468.5 6494.5 6500.6 6508.0 6508.6 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5534.90 Median:6275.11 stddev:1654.67 (26.4%) stddev-sans-first:1612.99 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: Values: 355.3 355.5 355.5 355.6 357.9 5979.8 6124.9 6160.4 6170.9 6214.8 5958.0 6086.5 6106.6 6122.7 6131.6 4749.1 4750.0 4752.1 4753.9 4754.2 4513.9 4635.7 4647.3 4713.0 4735.4 4681.6 4692.4 4697.7 4709.8 4729.7 4675.2 4680.8 4723.7 4726.1 4748.8 4196.0 4197.9 4205.9 4206.3 4207.6 5734.1 5848.6 5849.7 5851.8 5871.8 5512.5 5547.5 5551.4 5552.6 5560.3 6484.1 6489.9 6498.9 6501.3 6507.3 4542.8 4679.8 4683.5 4690.2 4705.2 6497.3 6608.8 6672.6 6786.7 6802.1 4814.4 4822.7 4823.4 4832.7 4840.7 4883.1 4893.2 4895.8 4896.7 4899.9 5482.6 5526.3 5564.5 5565.9 5569.8 6538.3 6569.9 6575.8 6584.2 6595.6 1699.1 1719.6 1721.5 1722.9 1725.9 1179.5 1180.9 1182.9 1193.7 1219.6 6293.4 6294.7 6295.5 6296.1 6303.6 6801.8 6806.1 6812.2 6817.4 6817.9 6256.8 6331.7 6372.3 6387.7 6392.1 3691.8 3715.5 3718.1 3720.0 3726.5 7037.7 7062.0 7069.4 7072.6 7083.2 6936.6 7024.3 7055.8 7061.0 7062.7 6990.6 7020.6 7029.2 7037.1 7038.5 6831.4 6874.9 6877.8 6890.4 6891.6 6677.2 6775.6 6779.2 6792.7 6805.2 6997.1 7030.1 7036.5 7041.7 7044.6 4590.2 4619.0 4621.9 4630.9 4632.4 6765.4 6906.1 6909.2 6911.5 6913.0 6704.9 6707.3 6708.2 6714.8 6718.2 6695.7 6701.9 6702.4 6710.7 6712.2 6439.1 6549.5 6586.2 6588.1 6594.5 6715.4 6718.4 6721.8 6724.3 6728.1 6568.6 6573.5 6575.1 6586.3 6586.7 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14879.06 Median:48173.52 stddev:17148.26 (35.6%) stddev-sans-first:17164.31 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: Values: 916.4 917.2 926.7 927.5 942.4 14590.4 15082.9 15467.4 15481.0 15484.0 14576.3 14588.7 14666.0 14695.3 14701.5 770.2 783.2 902.0 903.9 926.2 905.4 911.4 916.0 916.3 916.5 785.5 934.9 947.5 948.0 948.5 785.3 929.7 945.2 948.4 949.1 696.3 699.9 701.6 702.5 702.7 14212.1 14331.2 14354.6 14385.5 14388.7 12736.9 13142.8 13331.8 13358.3 13859.0 11297.1 11314.5 11452.5 11593.5 11719.5 3857.2 3861.9 3867.3 3869.2 3874.7 49919.3 50540.0 50998.7 51461.2 51596.2 10762.6 10801.0 10884.6 10932.9 10950.5 19392.8 19656.2 19663.0 19693.4 19710.7 8376.9 8421.4 8458.1 8685.3 8698.9 4771.5 4841.3 4849.4 4887.9 4987.8 3262.8 3283.1 3284.4 3288.4 3288.5 608.5 609.5 610.2 617.4 630.4 618.9 680.6 695.3 699.8 719.0 8994.6 9210.6 9288.8 9300.4 9757.4 713.9 737.1 740.9 742.3 749.2 4128.1 4246.2 4275.3 4315.1 4318.8 59697.4 59769.4 62242.0 62761.4 62887.7 55026.4 55662.4 55814.7 56142.0 56166.2 49305.0 49527.8 49688.8 50454.2 50494.0 8316.5 8644.1 8650.7 9113.1 9130.6 36810.6 37231.8 38827.4 38864.2 39303.2 47885.8 47889.0 48018.3 48328.8 49289.8 11049.6 11102.8 11644.7 11940.2 12502.6 13460.5 13728.5 13795.9 14137.0 14205.9 11842.7 11956.8 12124.3 12267.8 12868.8 11894.5 12107.2 12110.5 12121.0 12458.5 10831.3 11136.3 11286.2 11459.0 11496.4 9858.8 9947.0 9981.7 10208.5 10622.7 10078.7 10302.9 10363.9 10385.8 10404.2 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:07:01 INFO - 2016-01-25 04:07:01,469 DEBUG : BROWSER_OUTPUT: 04:07:02 INFO - 2016-01-25 04:07:02,250 INFO : Browser exited with error code: 0 04:07:02 INFO - 2016-01-25 04:07:02,273 INFO : Completed test dromaeo_css (00:18:32) 04:07:02 INFO - 2016-01-25 04:07:02,273 INFO : Starting test kraken 04:07:02 INFO - 2016-01-25 04:07:02,273 DEBUG : operating with platform_type : linux_ 04:07:02 INFO - 2016-01-25 04:07:02,273 INFO : Initialising browser for kraken test... 04:07:02 INFO - 2016-01-25 04:07:02,279 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpI1Q6q6/profile http://localhost:54299/getInfo.html 04:07:07 INFO - 2016-01-25 04:07:07,388 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:07:07 INFO - 2016-01-25 04:07:07,388 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:07:07 INFO - 2016-01-25 04:07:07,388 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:07:07 INFO - 2016-01-25 04:07:07,388 DEBUG : BROWSER_OUTPUT: __metrics 04:07:07 INFO - 2016-01-25 04:07:07,631 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] 04:07:09 INFO - 2016-01-25 04:07:09,001 INFO : Browser initialized. 04:07:09 INFO - 2016-01-25 04:07:09,002 INFO : Running cycle 1/1 for kraken test... 04:07:09 INFO - 2016-01-25 04:07:09,002 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpI1Q6q6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:07:20 INFO - 2016-01-25 04:07:20,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 137728000 04:07:20 INFO - 2016-01-25 04:07:20,771 DEBUG : BROWSER_OUTPUT: 04:07:23 INFO - 2016-01-25 04:07:23,231 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54299/tests/kraken/driver.html?testName=audio-beat-detection) 04:07:23 INFO - 2016-01-25 04:07:23,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 170438656 04:07:23 INFO - 2016-01-25 04:07:23,799 DEBUG : BROWSER_OUTPUT: 04:07:26 INFO - 2016-01-25 04:07:26,403 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54299/tests/kraken/driver.html?testName=audio-dft) 04:07:26 INFO - 2016-01-25 04:07:26,973 DEBUG : BROWSER_OUTPUT: RSS: Main: 169213952 04:07:26 INFO - 2016-01-25 04:07:26,973 DEBUG : BROWSER_OUTPUT: 04:07:29 INFO - 2016-01-25 04:07:29,758 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54299/tests/kraken/driver.html?testName=audio-fft) 04:07:30 INFO - 2016-01-25 04:07:30,325 DEBUG : BROWSER_OUTPUT: RSS: Main: 166658048 04:07:30 INFO - 2016-01-25 04:07:30,326 DEBUG : BROWSER_OUTPUT: 04:07:32 INFO - 2016-01-25 04:07:32,324 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54299/tests/kraken/driver.html?testName=audio-oscillator) 04:07:32 INFO - 2016-01-25 04:07:32,899 DEBUG : BROWSER_OUTPUT: RSS: Main: 167346176 04:07:32 INFO - 2016-01-25 04:07:32,899 DEBUG : BROWSER_OUTPUT: 04:07:35 INFO - 2016-01-25 04:07:35,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54299/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:07:35 INFO - 2016-01-25 04:07:35,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 164278272 04:07:35 INFO - 2016-01-25 04:07:35,656 DEBUG : BROWSER_OUTPUT: 04:07:38 INFO - 2016-01-25 04:07:38,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54299/tests/kraken/driver.html?testName=imaging-darkroom) 04:07:39 INFO - 2016-01-25 04:07:39,087 DEBUG : BROWSER_OUTPUT: RSS: Main: 164605952 04:07:39 INFO - 2016-01-25 04:07:39,087 DEBUG : BROWSER_OUTPUT: 04:07:42 INFO - 2016-01-25 04:07:42,475 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54299/tests/kraken/driver.html?testName=imaging-desaturate) 04:07:43 INFO - 2016-01-25 04:07:43,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 165511168 04:07:43 INFO - 2016-01-25 04:07:43,041 DEBUG : BROWSER_OUTPUT: 04:07:45 INFO - 2016-01-25 04:07:45,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54299/tests/kraken/driver.html?testName=json-parse-financial) 04:07:46 INFO - 2016-01-25 04:07:46,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 165904384 04:07:46 INFO - 2016-01-25 04:07:46,306 DEBUG : BROWSER_OUTPUT: 04:07:47 INFO - 2016-01-25 04:07:47,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54299/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:07:48 INFO - 2016-01-25 04:07:48,437 DEBUG : BROWSER_OUTPUT: RSS: Main: 171638784 04:07:48 INFO - 2016-01-25 04:07:48,437 DEBUG : BROWSER_OUTPUT: 04:07:50 INFO - 2016-01-25 04:07:50,192 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:07:50 INFO - 2016-01-25 04:07:50,795 DEBUG : BROWSER_OUTPUT: RSS: Main: 169328640 04:07:50 INFO - 2016-01-25 04:07:50,795 DEBUG : BROWSER_OUTPUT: 04:07:52 INFO - 2016-01-25 04:07:52,807 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:07:53 INFO - 2016-01-25 04:07:53,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 176295936 04:07:53 INFO - 2016-01-25 04:07:53,377 DEBUG : BROWSER_OUTPUT: 04:07:55 INFO - 2016-01-25 04:07:55,711 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:07:56 INFO - 2016-01-25 04:07:56,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 181641216 04:07:56 INFO - 2016-01-25 04:07:56,285 DEBUG : BROWSER_OUTPUT: 04:07:58 INFO - 2016-01-25 04:07:58,768 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:07:59 INFO - 2016-01-25 04:07:59,350 DEBUG : BROWSER_OUTPUT: RSS: Main: 181006336 04:07:59 INFO - 2016-01-25 04:07:59,350 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54299/tests/kraken/driver.html?testName=ai-astar) 04:08:01 INFO - 2016-01-25 04:08:01,278 DEBUG : BROWSER_OUTPUT: RSS: Main: 237428736 04:08:01 INFO - 2016-01-25 04:08:01,278 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,282 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:08:01 INFO - 2016-01-25 04:08:01,282 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:08:01 INFO - 2016-01-25 04:08:01,282 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:08:01 INFO - 2016-01-25 04:08:01,282 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:08:01 INFO - 2016-01-25 04:08:01,282 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;106;112;110;112;100;110;110;111 04:08:01 INFO - 2016-01-25 04:08:01,282 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;128;151;163;130;144;155;127;167;128 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;160;160;161;159;160;162;159;161 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |3;audio-fft;76;71;71;153;73;77;73;71;151;72 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;146;92;93;92;135;92;93;143 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;109;109;109;110;110;109 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;148;145;145;146;146;146;145;145;145 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;95;95;95;95;95;95 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;58;61;59;56;56;57;54;54 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;63;63;63;64;63;63;64;63;63 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;79;81;88;80;81;80;81;81;80 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;116;116;119;115;113;117;111;118;114 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;134;143;143;144;143;143;142;142;143 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;63;63;67;65;63;63;63;66 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,917 04:08:01 INFO - 2016-01-25 04:08:01,283 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:08:01 INFO - 2016-01-25 04:08:01,284 DEBUG : BROWSER_OUTPUT: __startTimestamp1453723681280__endTimestamp 04:08:01 INFO - 2016-01-25 04:08:01,284 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,285 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:08:01 INFO - 2016-01-25 04:08:01,285 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:08:01 INFO - 2016-01-25 04:08:01,285 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,285 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.10 Median:110.50 stddev:3.63 (3.3%) stddev-sans-first:3.86 04:08:01 INFO - 2016-01-25 04:08:01,285 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 106.0 112.0 110.0 112.0 100.0 110.0 110.0 111.0 04:08:01 INFO - 2016-01-25 04:08:01,285 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,286 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.70 Median:152.50 stddev:15.46 (10.1%) stddev-sans-first:16.03 04:08:01 INFO - 2016-01-25 04:08:01,286 DEBUG : BROWSER_OUTPUT: Values: 154.0 128.0 151.0 163.0 130.0 144.0 155.0 127.0 167.0 128.0 04:08:01 INFO - 2016-01-25 04:08:01,286 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,286 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 160.0 160.0 161.0 159.0 160.0 162.0 159.0 161.0 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.80 Median:72.50 stddev:33.38 (46.0%) stddev-sans-first:35.08 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: Values: 76.0 71.0 71.0 153.0 73.0 77.0 73.0 71.0 151.0 72.0 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.30 Median:92.50 stddev:23.65 (25.6%) stddev-sans-first:24.51 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 146.0 92.0 93.0 92.0 135.0 92.0 93.0 143.0 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 109.0 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: Values: 146.0 148.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:08:01 INFO - 2016-01-25 04:08:01,287 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:2.27 (4.0%) stddev-sans-first:2.35 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 58.0 61.0 59.0 56.0 56.0 57.0 54.0 54.0 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 63.0 63.0 64.0 63.0 63.0 64.0 63.0 63.0 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:2.58 (3.2%) stddev-sans-first:2.64 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: Values: 79.0 79.0 81.0 88.0 80.0 81.0 80.0 81.0 81.0 80.0 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.30 Median:116.00 stddev:2.41 (2.1%) stddev-sans-first:2.51 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: Values: 114.0 116.0 116.0 119.0 115.0 113.0 117.0 111.0 118.0 114.0 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.10 Median:143.00 stddev:2.92 (2.0%) stddev-sans-first:3.02 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: Values: 144.0 134.0 143.0 143.0 144.0 143.0 143.0 142.0 142.0 143.0 04:08:01 INFO - 2016-01-25 04:08:01,288 DEBUG : BROWSER_OUTPUT: 04:08:01 INFO - 2016-01-25 04:08:01,289 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.48 (2.3%) stddev-sans-first:1.56 04:08:01 INFO - 2016-01-25 04:08:01,289 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 63.0 63.0 67.0 65.0 63.0 63.0 63.0 66.0 04:08:01 INFO - 2016-01-25 04:08:01,289 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:08:01 INFO - 2016-01-25 04:08:01,289 DEBUG : BROWSER_OUTPUT: 04:08:02 INFO - 2016-01-25 04:08:02,562 INFO : Browser exited with error code: 0 04:08:02 INFO - 2016-01-25 04:08:02,618 INFO : Completed test kraken (00:01:00) 04:08:02 INFO - 2016-01-25 04:08:02,618 INFO : Starting test v8_7 04:08:02 INFO - 2016-01-25 04:08:02,618 DEBUG : operating with platform_type : linux_ 04:08:02 INFO - 2016-01-25 04:08:02,618 INFO : Initialising browser for v8_7 test... 04:08:02 INFO - 2016-01-25 04:08:02,624 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8LB28r/profile http://localhost:54299/getInfo.html 04:08:08 INFO - 2016-01-25 04:08:08,702 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:08:08 INFO - 2016-01-25 04:08:08,702 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:08:08 INFO - 2016-01-25 04:08:08,702 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:08:08 INFO - 2016-01-25 04:08:08,702 DEBUG : BROWSER_OUTPUT: __metrics 04:08:08 INFO - 2016-01-25 04:08:08,968 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] 04:08:09 INFO - 2016-01-25 04:08:09,944 INFO : Browser initialized. 04:08:09 INFO - 2016-01-25 04:08:09,944 INFO : Running cycle 1/1 for v8_7 test... 04:08:09 INFO - 2016-01-25 04:08:09,944 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8LB28r/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:08:21 INFO - 2016-01-25 04:08:21,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 137277440 04:08:21 INFO - 2016-01-25 04:08:21,725 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,069 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54299/tests/v8_7/run.html (next: http://localhost:54299/tests/v8_7/run.html) 04:08:43 INFO - 2016-01-25 04:08:43,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 423157760 04:08:43 INFO - 2016-01-25 04:08:43,319 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: |0;Richards;164.52780519907864 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;173.19016279875302 04:08:43 INFO - 2016-01-25 04:08:43,321 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.37310073157005 04:08:43 INFO - 2016-01-25 04:08:43,322 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 04:08:43 INFO - 2016-01-25 04:08:43,322 DEBUG : BROWSER_OUTPUT: |4;RayTrace;938.9671361502348 04:08:43 INFO - 2016-01-25 04:08:43,322 DEBUG : BROWSER_OUTPUT: |5;Earley;553.7098560354374 04:08:43 INFO - 2016-01-25 04:08:43,322 DEBUG : BROWSER_OUTPUT: |6;Boyer;11098.901098901099 04:08:43 INFO - 2016-01-25 04:08:43,322 DEBUG : BROWSER_OUTPUT: |7;RegExp;24390.243902439026 04:08:43 INFO - 2016-01-25 04:08:43,322 DEBUG : BROWSER_OUTPUT: |8;Splay;526.592943654555 04:08:43 INFO - 2016-01-25 04:08:43,323 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 04:08:43 INFO - 2016-01-25 04:08:43,323 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:08:43 INFO - 2016-01-25 04:08:43,323 DEBUG : BROWSER_OUTPUT: __startTimestamp1453723723320__endTimestamp 04:08:43 INFO - 2016-01-25 04:08:43,323 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,323 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:08:43 INFO - 2016-01-25 04:08:43,323 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:08:43 INFO - 2016-01-25 04:08:43,324 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,324 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.53 Median:164.53 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,324 DEBUG : BROWSER_OUTPUT: Values: 164.5 04:08:43 INFO - 2016-01-25 04:08:43,325 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,325 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:173.19 Median:173.19 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: Values: 173.2 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.37 Median:281.37 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: Values: 281.4 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: Values: 5181.3 04:08:43 INFO - 2016-01-25 04:08:43,326 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:938.97 Median:938.97 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: Values: 939.0 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.71 Median:553.71 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: Values: 553.7 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,327 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11098.90 Median:11098.90 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: Values: 11098.9 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24390.24 Median:24390.24 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: Values: 24390.2 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:526.59 Median:526.59 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: Values: 526.6 04:08:43 INFO - 2016-01-25 04:08:43,328 DEBUG : BROWSER_OUTPUT: 04:08:43 INFO - 2016-01-25 04:08:43,329 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 04:08:43 INFO - 2016-01-25 04:08:43,329 DEBUG : BROWSER_OUTPUT: Values: 4830.9 04:08:43 INFO - 2016-01-25 04:08:43,329 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:08:43 INFO - 2016-01-25 04:08:43,329 DEBUG : BROWSER_OUTPUT: 04:08:44 INFO - 2016-01-25 04:08:44,342 INFO : Browser exited with error code: 0 04:08:44 INFO - 2016-01-25 04:08:44,356 INFO : Completed test v8_7 (00:00:41) 04:08:44 INFO - 2016-01-25 04:08:44,383 INFO : Completed test suite (00:20:14) 04:08:44 INFO - 2016-01-25 04:08:44,383 DEBUG : Working with test: dromaeo_css 04:08:44 INFO - 2016-01-25 04:08:44,384 DEBUG : Generating results file: dromaeo_css 04:08:44 INFO - 2016-01-25 04:08:44,385 DEBUG : Working with test: kraken 04:08:44 INFO - 2016-01-25 04:08:44,385 DEBUG : Generating results file: kraken 04:08:44 INFO - 2016-01-25 04:08:44,385 INFO : javascript benchmark 04:08:44 INFO - 2016-01-25 04:08:44,385 DEBUG : Working with test: v8_7 04:08:44 INFO - 2016-01-25 04:08:44,385 DEBUG : Generating results file: v8_7 04:08:44 INFO - 2016-01-25 04:08:44,386 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:08:44 INFO - 2016-01-25 04:08:44,443 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 04:08:44 INFO - 2016-01-25 04:08:44,443 DEBUG : process_Request line: dromaeo_css 7255.98 graph.html#tests=[[72,52,35]] 04:08:44 INFO - 2016-01-25 04:08:44,443 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:08:44 INFO - 2016-01-25 04:08:44,479 DEBUG : process_Request line: kraken 1483.00 graph.html#tests=[[232,52,35]] 04:08:44 INFO - 2016-01-25 04:08:44,479 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:08:44 INFO - 2016-01-25 04:08:44,519 DEBUG : process_Request line: v8_7 23024.05 graph.html#tests=[[230,52,35]] 04:08:44 INFO - 2016-01-25 04:08:44,520 INFO : javascript benchmark 04:08:44 INFO - 2016-01-25 04:08:44,524 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3301.323228738109, 3347.7656564040526, 3375.4768010958296, 3379.3579219948224, 3386.8137252451106, 5502.669839809614, 5521.033819081655, 5523.0, 5525.088360420531, 5547.58393120516, 5498.780048798043, 5536.972315138429, 5548.55611551076, 5558.805441809537, 5562.554995600352, 4913.115639184945, 4994.051130285234, 5031.0, 5060.645754797148, 5123.333966584339, 5043.293938848564, 5068.315777370045, 5070.797168113271, 5089.3638295213095, 5097.413797413308, 4933.753312334369, 5003.0, 5028.421731500869, 5053.67151135175, 5124.795008199668, 5037.042961837257, 5115.872103197413, 5117.53942145205, 5118.232265160219, 5142.228665700138, 4703.553162449562, 5017.7491125443585, 5020.974895125538, 5045.596352291808, 5080.085584594772, 5236.29310043147, 5258.342707161605, 5304.973475132639, 5306.0, 5313.920291195635, 5777.884442311172, 5798.478136967693, 5828.9799285125355, 5837.0, 5843.912341314884, 5757.0, 5848.093545500455, 5868.882622347529, 5873.911891321633, 5929.407059294079, 5403.189521571757, 5441.83674489762, 5442.673439593637, 5468.56251499887, 5473.589480788957, 5975.223220981245, 6037.426444487766, 6060.090986352083, 6061.757529698851, 6071.0, 5540.141278101901, 5708.0, 5767.394423585547, 5864.38423965486, 5872.148538461899, 5880.705964701833, 5984.910226346608, 5992.850178745566, 5997.730102145414, 6000.03999360109, 5897.0, 5932.614380065368, 5958.880822383528, 5961.4038596140035, 5963.344032156459, 5622.775088996476, 5739.311282646026, 5926.525877929756, 5937.554683398763, 6070.392960703983, 4576.816927322936, 4579.267317229227, 4587.655925805511, 4602.447706275201, 4613.100445413179, 4102.794860256975, 4103.733257338264, 4103.774292413946, 4117.5882411759185, 4134.586541345841, 5437.700926449083, 5474.206240095244, 5536.390996990328, 5563.554915606742, 5646.378898321182, 5815.563832712563, 5818.883622327615, 5820.3888591698105, 5835.912461313083, 5872.677002764889, 5457.7271136443815, 5469.234307196995, 5479.945200547943, 5480.287562616834, 5487.6158668892795, 4930.482299358516, 5072.721000344943, 5161.974190129101, 5269.104252277122, 5296.285001524822, 6167.414095660905, 6188.8762224755255, 6190.907136392958, 6199.380061993765, 6200.441960223601, 5667.829965101054, 6012.549058820518, 6022.337542870193, 6035.637861728311, 6036.185115009594, 5953.374895635896, 6001.90997135052, 6092.546648701599, 6101.317092345618, 6107.341762062797, 5762.250907382014, 5804.245448091721, 5807.912881306699, 5817.447342502455, 5850.6782126981725, 5583.8883222336135, 5693.629914055573, 5871.647701137946, 5875.50058245054, 5894.498967587631, 5730.169125476866, 5799.594028417971, 5801.129830525455, 5805.187273781758, 5832.008558544972, 5753.0, 5767.336756273083, 5771.394003629726, 5772.5959182856795, 5773.336066352424, 5447.12845944647, 5543.029969755196, 5609.80365687199, 5637.5489960802215, 5677.886442271214, 5800.361960184461, 5846.41535846412, 5857.297124345106, 5863.1791549184, 5897.439743224387, 5666.801661941812, 5681.261436013292, 5710.942890571041, 5720.227769251098, 5755.8561035974435], "unit": "ms", "name": "dojo.html", "value": 5441.439014896341}, {"lowerIsBetter": false, "replicates": [591.742591972492, 608.6378604730183, 629.3203340392377, 636.0491065856544, 688.0058315733767, 31500.0, 32186.195345116383, 39577.20845583086, 40128.197436051334, 40171.59828401715, 35795.463068054, 36215.0, 36302.45546316807, 36323.45514817273, 36337.27325453496, 8066.838663226732, 8923.375363724526, 9075.455472671627, 9103.590338434753, 9106.18044376006, 8182.140875208107, 8271.710490132817, 8284.627191776355, 10478.31890927091, 10726.03465688088, 10558.788824223511, 10643.84034239487, 10652.573897044109, 10660.253782235246, 10663.413512256822, 10547.789044219111, 10616.150707943423, 10647.361158330525, 10649.201309901788, 10650.946745266301, 8293.502389856567, 8523.105073967268, 8527.18991695788, 10657.626983055556, 10922.453877306183, 44224.33663495018, 44315.0, 44402.0, 44425.55574444246, 44645.107097858185, 3548.929021419583, 3704.3887758519813, 3707.5736290326417, 3874.728768986172, 4003.719739618228, 33752.0, 33863.83068084669, 34314.48528272078, 34375.656243437494, 35181.824090879636, 4147.834086636531, 4212.304969679999, 4240.936385954213, 4260.403543503912, 4272.581934452457, 276608.0, 280822.0, 281281.0, 281831.0, 282158.0, 62431.063534047025, 70372.0, 71138.2886171142, 71142.2885771146, 71199.64400178069, 59431.0, 82933.17066829254, 83775.23011464377, 84309.57845210735, 84507.57746211352, 16372.672546549002, 16476.42332518356, 16592.17039148062, 16597.91701041487, 16598.087105209328, 12952.222866627844, 13092.541761038317, 13093.214407135602, 13099.607011789662, 13110.868891311156, 23203.303900883002, 23291.417714557272, 23319.300420987398, 23319.650205246933, 23505.882470587538, 8971.820563588692, 9231.492267925329, 9237.722868313962, 9354.0, 9357.0, 1005.301315585661, 1045.3048722599385, 1123.8707548632012, 1137.9871913996549, 1246.0592252849042, 29792.404151917275, 30682.53976190359, 30887.536686949712, 31411.37177256442, 31824.681753182635, 1083.2904447586843, 1114.5486078138367, 1116.084810455435, 1200.3338147328143, 1202.9879701203051, 14740.484083057256, 15017.324220410108, 15674.373025079098, 17519.299228030737, 17740.822591774177, 423019.0, 425413.0, 427712.0, 427751.0, 427855.0, 58878.411215887296, 60529.69735151384, 61142.0, 61156.38843611507, 61273.38726612766, 1343.1001229176475, 1419.1059632431502, 1428.4928850258216, 1433.5341014170435, 1445.06793118438, 29795.404091918474, 30101.548476772427, 30121.548176776927, 31073.378532429677, 31192.844035779675, 44782.0, 45224.32163517484, 47884.28173577469, 48917.0, 54106.45893541014, 214916.0, 214972.0, 215594.0, 215890.0, 215935.0, 33122.17194570155, 33358.33283334368, 33566.328673426884, 33611.49582756212, 33625.327493449506, 46688.0, 48075.0, 48177.27734083922, 48468.5153148464, 48677.26984095312, 38726.22547549089, 39287.01782455461, 39517.0, 39533.40699889562, 40660.1867962645, 39172.2165556693, 40153.99615009648, 40306.79846600866, 40497.7902991407, 40611.0, 39429.408558871066, 39638.60361396349, 40436.59563404328, 40472.79763601163, 40475.59524404718, 31673.0, 32822.835885820416, 33010.66989330076, 33038.33923321568, 33111.66888331086, 27025.729742702322, 27106.0, 27240.86379568169, 27318.726812731617, 27320.726792731817], "unit": "ms", "name": "ext.html", "value": 20110.784668896296}, {"lowerIsBetter": false, "replicates": [379.02968400893695, 380.51294343240664, 386.7621412831108, 388.69099066242336, 389.9083715326898, 14959.700805983874, 16707.415240466587, 17344.56638584036, 19875.409967202606, 19885.105170267336, 16889.408870689527, 17322.480325590222, 17326.133693315347, 18647.25410983559, 19108.522286942833, 5113.642045056848, 5230.0, 5237.842864714045, 5243.292155559008, 5267.52592266696, 3952.1898010907694, 4041.8383264669524, 4042.8584999525024, 4046.4941882264716, 5155.793768249266, 5500.477454641803, 5687.744051517691, 5702.65784052956, 5714.285714285715, 5716.1711551824965, 5514.145307477347, 6196.907046394308, 6198.039303907902, 6200.844978875519, 6205.658688772116, 5426.240326354314, 5464.180372944051, 5466.972665136649, 5484.2596249506205, 5519.779208831682, 12030.759384812342, 12104.757904841854, 12191.0, 12229.510819567207, 12802.103852730312, 2240.3950933247934, 2242.843000989931, 2247.6628505724107, 2249.224017713886, 2307.3193407944527, 9899.0, 10579.31234469757, 13167.078304518687, 13253.3373331334, 13559.661008474866, 1948.7589272336927, 1976.9604607907904, 1994.481434826941, 2068.3588087692865, 2093.9790602093935, 61123.69438152781, 64564.3543564563, 65279.34720652828, 65721.342786572, 65896.01155982712, 10757.892421075689, 10923.617673381394, 11036.834447483294, 11041.558337666564, 11115.777684446266, 14545.92727036358, 14549.563513094625, 14568.490102846561, 14681.0, 14713.411463541552, 7902.565358905201, 7928.286454219148, 7988.84022319562, 7994.40041996841, 7997.6800927963395, 7512.699492020367, 7519.323260906544, 7520.661570229353, 7574.5076570022775, 7575.0, 4638.5593368629925, 4712.387389639325, 4745.383100196952, 4812.06164797868, 4852.490488498727, 1792.8924264544169, 1815.6731788277973, 1827.6436094961355, 1831.3415919349525, 1831.8970875620962, 451.6071018214175, 963.210167662524, 972.9854052189223, 1060.5174645536263, 1136.3295655563256, 9706.0, 10721.624743133953, 10891.0, 10899.400532970763, 10973.615923442801, 475.04752970295027, 481.4295060353492, 485.83481616250646, 487.05025200858466, 488.5627363509639, 6296.590721603081, 6423.229212494531, 6474.255460621995, 6496.967515162394, 10109.39343639384, 612352.0, 615831.0, 624499.0, 669856.0, 673222.0, 82569.0, 85207.14792851992, 85901.57049214838, 86139.0, 87938.0, 1221.590767093016, 1226.607485604598, 1266.2465832829453, 1275.1647670775658, 1295.8963282937343, 16278.348866045699, 16474.0, 16568.17159142062, 16609.501714948572, 16625.584360391087, 37666.811665942594, 38062.80968595139, 38497.807510962266, 38709.2258154841, 38858.80570597129, 49927.251091234386, 50391.74804125956, 50959.23561146512, 51364.74317628388, 51751.2237316433, 9530.380525265835, 9621.56702948383, 9718.173955213602, 9731.318807683394, 9740.902590974, 15396.84603153954, 17405.82594174042, 17956.0, 18103.547411314823, 18994.905025474785, 14940.626484337978, 15211.923940380228, 15274.236288185768, 15336.0, 15613.297401617181, 14298.85701143017, 14589.270536473347, 14691.485797997018, 15252.618684532976, 15705.450309239122, 13169.934150329187, 13484.595462136152, 14201.502947396792, 15222.0, 15586.0, 9934.155596774355, 10025.398476091457, 10115.342502737298, 10144.847827282745, 10152.898471015194, 13360.331983400985, 14410.351534180778, 15202.0, 15515.84484155144, 16022.439214627431], "unit": "ms", "name": "jquery.html", "value": 8834.95192325394}, {"lowerIsBetter": false, "replicates": [1523.7028779388024, 1530.3572499550187, 1612.3953517430964, 1776.2539733312008, 1819.6997495413254, 6446.742130314788, 7422.072240969879, 7538.434617403689, 7552.244775522445, 7564.357029652486, 7405.1854296027395, 7601.20187380324, 7609.391248700104, 7616.847663046736, 7617.8476430471355, 1980.3365872432753, 2005.9899700501478, 2006.9197232110698, 2258.7289525256992, 2289.5077558324956, 2220.6335954567476, 2288.794008539231, 2291.7708229177115, 2292.083166733303, 2293.5068960173558, 2220.888955552224, 2239.932802015934, 2282.600544904635, 2283.954320913581, 2286.2341115726254, 2256.187772401934, 2263.569921714869, 2264.682944387787, 2280.2361208995007, 2291.633338665814, 2277.1119263487253, 2286.2683941138844, 2316.143027079991, 2321.3268152235833, 2323.59337116004, 7163.606001669906, 7234.421246300283, 7240.0, 7240.782776516713, 7250.383717383976, 4439.711418757771, 4500.437445319335, 4506.098780243931, 4527.0, 4540.636749060067, 3026.7729920255847, 3038.3315670552665, 3085.059056987618, 3089.830059346735, 3120.7971481853606, 997.481309718942, 1002.72425083102, 1006.1900170362856, 1014.6144465103288, 1052.4106500359746, 10180.236482263786, 10240.436775977318, 10249.795004099951, 10277.17782577392, 10286.074253317238, 3981.0843505993203, 3982.422548730418, 4055.270051390719, 4065.6137666921595, 4067.8576249831704, 8920.152585504367, 8938.061503542165, 8950.104989501127, 8952.507612081397, 8963.417377870417, 4527.162474942146, 4541.84103556374, 4567.954320456819, 4569.1547063793305, 4576.565226303496, 4160.313548264563, 4196.706230563892, 4198.958010419918, 4207.621314081686, 4225.492940847118, 3242.854071566785, 3381.4420620597816, 3390.9152271193416, 3630.0380399194237, 3704.1850792825535, 1169.8479197704244, 1191.243560339191, 1193.4390836306923, 1195.4261954262004, 1208.6796998795326, 1115.726646971497, 1115.9888401116048, 1141.001623579368, 1275.3177050278057, 1348.5077946549438, 7080.822979425452, 7094.92905070953, 7161.427085833121, 7165.6417179140835, 7173.175084865203, 1095.27164435651, 1111.9436535291507, 1126.9688235264641, 1127.6166103524677, 1154.7690461907484, 5450.04624190765, 5499.285092937893, 5503.642263252876, 5506.421825708243, 5509.752061157257, 8672.8699069514, 8789.560521973875, 8950.14973577509, 8974.37179397449, 9115.954420227989, 8871.467711937305, 8980.057094005035, 8985.550722463851, 8998.055204203596, 9001.909980900238, 7067.2579379164445, 7190.06529151207, 7204.855902881913, 7253.746118885813, 7253.782386528413, 7076.716931322895, 7153.320434558708, 7155.391791697556, 7163.462740294603, 7168.462365322517, 8142.8778568322705, 8456.196661317164, 8460.873086903825, 8466.872996905175, 8479.321654267767, 8444.957775211085, 8488.363372746946, 8496.150384961455, 8500.192481714228, 8672.132786721277, 6732.798016059526, 6734.259231484631, 6814.591124532544, 6821.522493425413, 6831.111955445959, 6205.906911396423, 6322.0, 7243.674034668347, 7369.8157546061775, 7427.70289188448, 6902.24075351721, 6931.237563868071, 7003.3346832050875, 7056.6471676417, 7080.504364694422, 6920.4809639276245, 6956.0609317741455, 6996.545224560387, 7065.717371305091, 7073.363397294268, 7016.368526832405, 7038.0, 7064.540804847668, 7066.081409416743, 7078.787636370917, 6305.1172835803945, 6355.491560675043, 6357.3006969234275, 6418.935810641834, 6426.068220108152, 6468.5472016958365, 6494.480441564758, 6500.609963402211, 6508.0, 6508.642024688687], "unit": "ms", "name": "mootools.html", "value": 4411.708678209485}, {"lowerIsBetter": false, "replicates": [355.2699203137553, 355.48277256591655, 355.5378008588834, 355.63725000499477, 357.8711663801031, 5979.7907073252445, 6124.877502449948, 6160.383961603843, 6170.907436388457, 6214.782482613109, 5958.0, 6086.452219300262, 6106.633601983873, 6122.663253521055, 6131.632102073879, 4749.097671442432, 4750.049990001996, 4752.12085764133, 4753.85738427846, 4754.191787396151, 4513.894095946498, 4635.745034023127, 4647.32613771003, 4712.976435117819, 4735.360726301939, 4681.578657920786, 4692.4369075710965, 4697.718136911779, 4709.835155769549, 4729.716217026972, 4675.158471475133, 4680.789364478606, 4723.740194289313, 4726.054789042205, 4748.7890587899865, 4196.0, 4197.874063778091, 4205.852795152155, 4206.348016057516, 4207.579242075768, 5734.053881109612, 5848.649081055108, 5849.678267695275, 5851.824445266649, 5871.765129394819, 5512.531434828044, 5547.528460080898, 5551.361593416728, 5552.611317207797, 5560.304961879765, 6484.092227088211, 6489.935100648981, 6498.902516462257, 6501.31736167705, 6507.3492650735025, 4542.818287268506, 4679.812807487697, 4683.461401938786, 4690.1557719610455, 4705.200115980291, 6497.252815926182, 6608.801735947929, 6672.566283191577, 6786.72853085876, 6802.115724955774, 4814.374131362901, 4822.734749588806, 4823.421189457217, 4832.734199619054, 4840.685355451885, 4883.072216278895, 4893.192623217201, 4895.804167833318, 4896.657233993657, 4899.853004409873, 5482.643628164156, 5526.281583394133, 5564.4991950724625, 5565.916511252334, 5569.777208911598, 6538.346165383424, 6569.915963866004, 6575.80272591823, 6584.242812076577, 6595.604263744191, 1699.0740046674591, 1719.5701074731317, 1721.5179749670112, 1722.922468488921, 1725.8533024693043, 1179.4869231884159, 1180.9055275577932, 1182.869884312725, 1193.6777070191174, 1219.615821016377, 6293.370662933761, 6294.653794041372, 6295.527835412362, 6296.118543403927, 6303.621782693053, 6801.761938332134, 6806.115205023315, 6812.216595091628, 6817.386435220854, 6817.931820681829, 6256.843578910563, 6331.746730130743, 6372.330905254881, 6387.712552935129, 6392.105105285264, 3691.7600355976774, 3715.5169827922737, 3718.1076541629814, 3719.962800372016, 3726.4969229153717, 7037.683304251321, 7062.0, 7069.363757261871, 7072.611006394594, 7083.220845706968, 6936.618485983319, 7024.297570242935, 7055.8236044098285, 7060.964695176492, 7062.71749130029, 6990.61551614666, 7020.5787652741, 7029.191642961023, 7037.120359955005, 7038.507304488637, 6831.419329357061, 6874.9312506876295, 6877.828054298682, 6890.414314783299, 6891.620960847202, 6677.165354330708, 6775.593464392152, 6779.15260592426, 6792.728290868507, 6805.1833779944745, 6997.05539752127, 7030.121234845645, 7036.5074444790425, 7041.683124259318, 7044.647767611701, 4590.219662657423, 4619.0, 4621.884452888705, 4630.907381852277, 4632.420947381577, 6765.357291057342, 6906.06768086322, 6909.239983601699, 6911.516193866582, 6912.965435172792, 6704.932950670431, 6707.295733947865, 6708.22855371619, 6714.83212919681, 6718.193816742022, 6695.698693558704, 6701.865962680621, 6702.430293425114, 6710.664466776544, 6712.160979877515, 6439.098526206429, 6549.4760419165395, 6586.209654841449, 6588.077669126422, 6594.4724422045165, 6715.3620406061345, 6718.361755633207, 6721.831954201184, 6724.29394913527, 6728.125343705483, 6568.638724870178, 6573.5069869759, 6575.079488871657, 6586.341365863567, 6586.70359833799], "unit": "ms", "name": "prototype.html", "value": 5004.513954656598}, {"lowerIsBetter": false, "replicates": [916.4455504419826, 917.2157805076663, 926.6525053105086, 927.5176908007836, 942.3544871762839, 14590.416383344667, 15082.924585377072, 15467.381304747796, 15480.999999999985, 15484.0, 14576.271186440663, 14588.708225835477, 14666.0, 14695.265236738147, 14701.485448009322, 770.2374649097382, 783.2402569507568, 902.025812122909, 903.9457632542036, 926.2173463423396, 905.4069584422214, 911.4030310146865, 916.0107084348887, 916.2532535983153, 916.5279880861361, 785.5286827903269, 934.9111834375724, 947.4741518457254, 947.9952600237024, 948.5209968965697, 785.2657764989724, 929.7257309093834, 945.1588086602928, 948.4451595816456, 949.093615597103, 696.3350000749273, 699.8600279944031, 701.5650296816007, 702.5293053654013, 702.7259368846154, 14212.147271163763, 14331.211783351911, 14354.64113397163, 14385.496507622287, 14388.712225755531, 12736.872631273754, 13142.802857957138, 13331.800022999663, 13358.332083395791, 13859.0, 11297.096232301396, 11314.490847911864, 11452.484638191301, 11593.536258549648, 11719.531218751239, 3857.2092720992127, 3861.903452413684, 3867.284552357795, 3869.1681288523105, 3874.6900247980093, 49919.25121123186, 50540.0, 50998.7450062751, 51461.2280815788, 51596.22605660881, 10762.569497220024, 10801.0, 10884.564617415374, 10932.945335273273, 10950.507227174796, 19392.8060719391, 19656.21375145007, 19663.016849157484, 19693.40919772409, 19710.70433943492, 8376.874346884802, 8421.368397370221, 8458.069612342637, 8685.261752750961, 8698.869516957251, 4771.475137734847, 4841.298011788342, 4849.393825771778, 4887.877803054952, 4987.775550100254, 3262.8205448700073, 3283.129970557804, 3284.441644920369, 3288.4245257079915, 3288.490284005983, 608.4797498139116, 609.5276160975279, 610.1793088296234, 617.4350469320522, 630.426312056036, 618.9226346706662, 680.6392611915594, 695.3463744080548, 699.8285420072115, 718.9532680375759, 8994.550272486349, 9210.585523651453, 9288.767780805534, 9300.441973481613, 9757.414555126716, 713.8970290900571, 737.0896942276285, 740.8814589665627, 742.279988411251, 749.1759065028534, 4128.112455821953, 4246.235677578004, 4275.251830929575, 4315.07225946423, 4318.827246910151, 59697.40302597005, 59769.40230597725, 62242.0, 62761.37238627555, 62887.685561571896, 55026.44973550293, 55662.44337556572, 55814.72092639511, 56142.0, 56166.15750763742, 49305.0, 49527.75236123797, 49688.75155624199, 50454.243186352236, 50493.999999999265, 8316.501009939422, 8644.09237030128, 8650.653973841228, 9113.134252246025, 9130.589123489326, 36810.63189368179, 37231.81384093062, 38827.417588735625, 38864.22271554609, 39303.213935721695, 47885.76057119692, 47889.0, 48018.27972580484, 48328.758356207996, 49289.75355123202, 11049.558017679203, 11102.777944440904, 11644.6506604802, 11940.223885447418, 12502.562410315595, 13460.528881489101, 13728.519501817389, 13795.862041379458, 14137.0, 14205.857941420454, 11842.703932401759, 11956.820647690118, 12124.298524154832, 12267.75464490723, 12868.806967895302, 11894.524219031488, 12107.152499324962, 12110.45502952387, 12121.0, 12458.501659933501, 10831.2959657622, 11136.276142050741, 11286.20996530235, 11459.0, 11496.367699776592, 9858.753531161778, 9946.950265248628, 9981.74982298315, 10208.54061567246, 10622.681319560426, 10078.697639070839, 10302.896971030494, 10363.896361036293, 10385.792284154426, 10404.219683523615], "unit": "ms", "name": "yui.html", "value": 6836.283173582529}], "name": "dromaeo_css", "value": 7255.978521664783}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 106.0, 112.0, 110.0, 112.0, 100.0, 110.0, 110.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 109.1}, {"lowerIsBetter": true, "replicates": [154.0, 128.0, 151.0, 163.0, 130.0, 144.0, 155.0, 127.0, 167.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.7}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 160.0, 160.0, 161.0, 159.0, 160.0, 162.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [76.0, 71.0, 71.0, 153.0, 73.0, 77.0, 73.0, 71.0, 151.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.8}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 146.0, 92.0, 93.0, 92.0, 135.0, 92.0, 93.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 107.3}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 148.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 58.0, 61.0, 59.0, 56.0, 56.0, 57.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.3}, {"lowerIsBetter": true, "replicates": [79.0, 79.0, 81.0, 88.0, 80.0, 81.0, 80.0, 81.0, 81.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.0}, {"lowerIsBetter": true, "replicates": [114.0, 116.0, 116.0, 119.0, 115.0, 113.0, 117.0, 111.0, 118.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.3}, {"lowerIsBetter": true, "replicates": [144.0, 134.0, 143.0, 143.0, 144.0, 143.0, 143.0, 142.0, 142.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.1}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 63.0, 63.0, 67.0, 65.0, 63.0, 63.0, 63.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.2}], "name": "kraken", "value": 1482.9999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.52780519907864], "unit": "score", "name": "Richards", "value": 214.56555600000002}, {"lowerIsBetter": false, "replicates": [173.19016279875302], "unit": "score", "name": "DeltaBlue", "value": 381.76533200000006}, {"lowerIsBetter": false, "replicates": [281.37310073157005], "unit": "score", "name": "Encrypt", "value": 946.0072739999998}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [938.9671361502348], "unit": "score", "name": "RayTrace", "value": 788.0882850000002}, {"lowerIsBetter": false, "replicates": [553.7098560354374], "unit": "score", "name": "Earley", "value": 1203.6321779999998}, {"lowerIsBetter": false, "replicates": [11098.901098901099], "unit": "score", "name": "Boyer", "value": 60.04765643564352}, {"lowerIsBetter": false, "replicates": [24390.243902439026], "unit": "score", "name": "RegExp", "value": 37.350384999999974}, {"lowerIsBetter": false, "replicates": [526.592943654555], "unit": "score", "name": "Splay", "value": 154.75140899999997}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23024.048224506918}]} 04:08:44 INFO - RETURN: dromaeo_css: 7255.98 04:08:44 INFO - RETURN: kraken: 1483.00 04:08:44 INFO - RETURN: v8_7: 23024.05 04:08:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "23024.05"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1483.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7255.98"}}} 04:08:44 INFO - Return code: 0 04:08:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 04:08:44 INFO - # TBPL SUCCESS # 04:08:44 INFO - Running post-action listener: _resource_record_post_action 04:08:44 INFO - Running post-run listener: _resource_record_post_run 04:08:45 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 4096; Write bytes: 519118848; Read time: 40; Write time: 752476 04:08:45 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 247943168; Read time: 0; Write time: 483488 04:08:45 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 4096; Write bytes: 270577664; Read time: 40; Write time: 268988 04:08:45 INFO - Running post-run listener: _upload_blobber_files 04:08:45 INFO - Blob upload gear active. 04:08:45 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:08:45 INFO - Copying logs to upload dir... 04:08:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1351.161410 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 31 secs) (at 2016-01-25 04:08:45.333584) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-25 04:08:45.340643) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010060 build_url: 'https://queue.taskcluster.net/v1/task/hWuNuRHrQOytGSTwM74O8w/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-25 04:08:45.391065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 04:08:45.391388) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1453718770.498223-289722886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 04:08:45.443376) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-25 04:08:45.443676) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-25 04:08:45.443956) ========= ========= Total master_lag: 0.38 =========