builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1448332178.94 results: success (0) buildid: 20151123173004 builduid: 08e1d62f389c4ada9a7a09a24c31c420 revision: 65cd2c7696e438c74bfbb0ad40e18f411dbead38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.943772) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.944285) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.944633) ========= 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006717 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.970864) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.971357) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.988275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.988665) ========= 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004680 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.043464) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.043825) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.044236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.044548) ========= 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 18:29:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.4M=0s 2015-11-23 18:29:40 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174494 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.233249) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.233602) ========= 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020832 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.283317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.283732) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 65cd2c7696e438c74bfbb0ad40e18f411dbead38 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 65cd2c7696e438c74bfbb0ad40e18f411dbead38 --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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 18:29:40,298 truncating revision to first 12 chars 2015-11-23 18:29:40,298 Setting DEBUG logging. 2015-11-23 18:29:40,298 attempt 1/10 2015-11-23 18:29:40,298 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/65cd2c7696e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 18:29:40,564 unpacking tar archive at: fx-team-65cd2c7696e4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.895848 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.194111) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.194457) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.203328) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.203804) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.204303) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-11-23 18:29:40.204713) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:41 INFO - MultiFileLogger online at 20151123 18:29:41 in /builds/slave/test-pgo 18:29:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:29:41 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:29:41 INFO - {'append_to_log': False, 18:29:41 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:29:41 INFO - 'blob_upload_branch': 'Fx-Team', 18:29:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:29:41 INFO - 'branch': 'Fx-Team', 18:29:41 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:41 INFO - 'config_files': ('talos/linux_config.py',), 18:29:41 INFO - 'default_actions': ('clobber', 18:29:41 INFO - 'read-buildbot-config', 18:29:41 INFO - 'download-and-extract', 18:29:41 INFO - 'populate-webroot', 18:29:41 INFO - 'create-virtualenv', 18:29:41 INFO - 'install', 18:29:41 INFO - 'run-tests'), 18:29:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:41 INFO - 'download_minidump_stackwalk': True, 18:29:41 INFO - 'download_symbols': 'ondemand', 18:29:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:41 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:41 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:41 INFO - 'installer_path': 'installer.exe', 18:29:41 INFO - 'log_level': 'info', 18:29:41 INFO - 'log_name': 'talos', 18:29:41 INFO - 'log_to_console': True, 18:29:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:41 INFO - 'opt_config_files': (), 18:29:41 INFO - 'pip_index': False, 18:29:41 INFO - 'sps_profile': False, 18:29:41 INFO - 'sps_profile_interval': 0, 18:29:41 INFO - 'suite': 'dromaeojs', 18:29:41 INFO - 'system_bits': '64', 18:29:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:41 INFO - 'title': 'talos-linux64-ix-024', 18:29:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:41 INFO - 'use_talos_json': True, 18:29:41 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:29:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:41 INFO - 'work_dir': 'build'} 18:29:41 INFO - ##### 18:29:41 INFO - ##### Running clobber step. 18:29:41 INFO - ##### 18:29:41 INFO - Running pre-action listener: _resource_record_pre_action 18:29:41 INFO - Running main action method: clobber 18:29:41 INFO - rmtree: /builds/slave/test-pgo/build 18:29:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:29:41 INFO - Running post-action listener: _resource_record_post_action 18:29:41 INFO - ##### 18:29:41 INFO - ##### Running read-buildbot-config step. 18:29:41 INFO - ##### 18:29:41 INFO - Running pre-action listener: _resource_record_pre_action 18:29:41 INFO - Running main action method: read_buildbot_config 18:29:41 INFO - Using buildbot properties: 18:29:41 INFO - { 18:29:41 INFO - "properties": { 18:29:41 INFO - "buildnumber": 510, 18:29:41 INFO - "product": "firefox", 18:29:41 INFO - "script_repo_revision": "production", 18:29:41 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:29:41 INFO - "repository": "", 18:29:41 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 18:29:41 INFO - "buildid": "20151123173004", 18:29:41 INFO - "slavename": "talos-linux64-ix-024", 18:29:41 INFO - "pgo_build": "True", 18:29:41 INFO - "basedir": "/builds/slave/test-pgo", 18:29:41 INFO - "project": "", 18:29:41 INFO - "platform": "ubuntu64_hw", 18:29:41 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:29:41 INFO - "slavebuilddir": "test", 18:29:41 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:29:41 INFO - "branch": "fx-team", 18:29:41 INFO - "repo_path": "integration/fx-team", 18:29:41 INFO - "stage_platform": "linux64-pgo", 18:29:41 INFO - "builduid": "08e1d62f389c4ada9a7a09a24c31c420", 18:29:41 INFO - "revision": "65cd2c7696e438c74bfbb0ad40e18f411dbead38" 18:29:41 INFO - }, 18:29:41 INFO - "sourcestamp": { 18:29:41 INFO - "repository": "", 18:29:41 INFO - "hasPatch": false, 18:29:41 INFO - "project": "", 18:29:41 INFO - "branch": "fx-team-linux64-pgo-talos", 18:29:41 INFO - "changes": [ 18:29:41 INFO - { 18:29:41 INFO - "category": null, 18:29:41 INFO - "files": [ 18:29:41 INFO - { 18:29:41 INFO - "url": null, 18:29:41 INFO - "name": "https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:29:41 INFO - } 18:29:41 INFO - ], 18:29:41 INFO - "repository": "", 18:29:41 INFO - "rev": "65cd2c7696e438c74bfbb0ad40e18f411dbead38", 18:29:41 INFO - "who": "sendchange", 18:29:41 INFO - "when": 1448332155, 18:29:41 INFO - "number": 6698511, 18:29:41 INFO - "comments": "", 18:29:41 INFO - "project": "", 18:29:41 INFO - "at": "Mon 23 Nov 2015 18:29:15", 18:29:41 INFO - "branch": "fx-team-linux64-pgo-talos", 18:29:41 INFO - "revlink": "", 18:29:41 INFO - "properties": [ 18:29:41 INFO - [ 18:29:41 INFO - "buildid", 18:29:41 INFO - "20151123173004", 18:29:41 INFO - "Change" 18:29:41 INFO - ], 18:29:41 INFO - [ 18:29:41 INFO - "builduid", 18:29:41 INFO - "08e1d62f389c4ada9a7a09a24c31c420", 18:29:41 INFO - "Change" 18:29:41 INFO - ], 18:29:41 INFO - [ 18:29:41 INFO - "pgo_build", 18:29:41 INFO - "True", 18:29:41 INFO - "Change" 18:29:41 INFO - ] 18:29:41 INFO - ], 18:29:41 INFO - "revision": "65cd2c7696e438c74bfbb0ad40e18f411dbead38" 18:29:41 INFO - } 18:29:41 INFO - ], 18:29:41 INFO - "revision": "65cd2c7696e438c74bfbb0ad40e18f411dbead38" 18:29:41 INFO - } 18:29:41 INFO - } 18:29:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:29:41 INFO - Running post-action listener: _resource_record_post_action 18:29:41 INFO - ##### 18:29:41 INFO - ##### Running download-and-extract step. 18:29:41 INFO - ##### 18:29:41 INFO - Running pre-action listener: _resource_record_pre_action 18:29:41 INFO - Running main action method: download_and_extract 18:29:41 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:29:41 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')]} 18:29:41 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:29:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json 18:29:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json 18:29:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:29:44 INFO - Downloaded 1302 bytes. 18:29:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:29:44 INFO - Using the following test package requirements: 18:29:44 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:29:44 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'jsshell-linux-x86_64.zip'], 18:29:44 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:29:44 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:29:44 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:29:44 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:29:44 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:29:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:44 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:46 INFO - Downloaded 22526486 bytes. 18:29:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:29:47 INFO - Return code: 0 18:29:47 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:47 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:48 INFO - Downloaded 22526486 bytes. 18:29:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:29:50 INFO - Return code: 0 18:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:50 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:29:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:29:50 INFO - Downloaded 11130997 bytes. 18:29:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:29:51 INFO - Return code: 0 18:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:51 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:29:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:29:52 INFO - Downloaded 57581985 bytes. 18:29:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:52 INFO - mkdir: /builds/slave/test-pgo/properties 18:29:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:29:52 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:29:52 INFO - Contents: 18:29:52 INFO - build_url:https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:52 INFO - Running post-action listener: _resource_record_post_action 18:29:52 INFO - Running post-action listener: set_extra_try_arguments 18:29:52 INFO - ##### 18:29:52 INFO - ##### Running populate-webroot step. 18:29:52 INFO - ##### 18:29:52 INFO - Running pre-action listener: _resource_record_pre_action 18:29:52 INFO - Running main action method: populate_webroot 18:29:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:29:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:29:52 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:29:52 INFO - '../fennec_ids.txt'], 18:29:52 INFO - 'tests': ['tcheck2']}, 18:29:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:29:52 INFO - '--tppagecycles', 18:29:52 INFO - '7'], 18:29:52 INFO - 'tests': ['tsvgm']}}, 18:29:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:29:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:29:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:29:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:29:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'tests': ['damp', 'tps']}, 18:29:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['damp', 'tps']}, 18:29:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:29:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:52 INFO - 'other': {'tests': ['a11yr', 18:29:52 INFO - 'ts_paint', 18:29:52 INFO - 'tpaint', 18:29:52 INFO - 'sessionrestore', 18:29:52 INFO - 'sessionrestore_no_auto_restore']}, 18:29:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['a11yr', 18:29:52 INFO - 'ts_paint', 18:29:52 INFO - 'tpaint', 18:29:52 INFO - 'sessionrestore', 18:29:52 INFO - 'sessionrestore_no_auto_restore']}, 18:29:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['a11yr', 18:29:52 INFO - 'ts_paint', 18:29:52 INFO - 'tpaint', 18:29:52 INFO - 'sessionrestore', 18:29:52 INFO - 'sessionrestore_no_auto_restore']}, 18:29:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['a11yr', 18:29:52 INFO - 'ts_paint', 18:29:52 INFO - 'tpaint', 18:29:52 INFO - 'sessionrestore', 18:29:52 INFO - 'sessionrestore_no_auto_restore']}, 18:29:52 INFO - 'other_l64': {'tests': ['a11yr', 18:29:52 INFO - 'ts_paint', 18:29:52 INFO - 'tpaint', 18:29:52 INFO - 'sessionrestore', 18:29:52 INFO - 'sessionrestore_no_auto_restore']}, 18:29:52 INFO - 'other_nol64': {'tests': ['a11yr', 18:29:52 INFO - 'ts_paint', 18:29:52 INFO - 'tpaint', 18:29:52 INFO - 'sessionrestore', 18:29:52 INFO - 'sessionrestore_no_auto_restore']}, 18:29:52 INFO - 'svgr': {'tests': ['tsvgx', 18:29:52 INFO - 'tsvgr_opacity', 18:29:52 INFO - 'tart', 18:29:52 INFO - 'tscrollx', 18:29:52 INFO - 'cart']}, 18:29:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['tsvgx', 18:29:52 INFO - 'tsvgr_opacity', 18:29:52 INFO - 'tart', 18:29:52 INFO - 'tscrollx', 18:29:52 INFO - 'cart']}, 18:29:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'tests': ['tp5o']}, 18:29:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'talos_options': ['--e10s'], 18:29:52 INFO - 'tests': ['tp5o']}, 18:29:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'talos_options': ['--xperf_path', 18:29:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:52 INFO - 'tests': ['tp5n']}, 18:29:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:52 INFO - 'talos_options': ['--e10s', 18:29:52 INFO - '--xperf_path', 18:29:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:52 INFO - 'tests': ['tp5n']}}, 18:29:52 INFO - 'talos.zip': {'path': '', 18:29:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:29:52 INFO - Running post-action listener: _resource_record_post_action 18:29:52 INFO - ##### 18:29:52 INFO - ##### Running create-virtualenv step. 18:29:52 INFO - ##### 18:29:52 INFO - Running pre-action listener: _resource_record_pre_action 18:29:52 INFO - Running main action method: create_virtualenv 18:29:52 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:29:52 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 18:29:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:29:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:52 INFO - Using real prefix '/usr' 18:29:52 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:29:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:29:55 INFO - Installing pip.................done. 18:29:55 INFO - Return code: 0 18:29:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:29: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')]} 18:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29: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')]} 18:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:29:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:29:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:29:55 INFO - Using env: {'DISPLAY': ':0', 18:29:55 INFO - 'HOME': '/home/cltbld', 18:29:55 INFO - 'LANG': 'en_US.UTF-8', 18:29:55 INFO - 'LANGUAGE': 'en_US:en', 18:29:55 INFO - 'LOGNAME': 'cltbld', 18:29:55 INFO - 'MAIL': '/var/mail/cltbld', 18:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:55 INFO - 'MOZ_NO_REMOTE': '1', 18:29:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:55 INFO - 'NO_EM_RESTART': '1', 18:29:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:29:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:29:55 INFO - 'SHELL': '/bin/bash', 18:29:55 INFO - 'SHLVL': '1', 18:29:55 INFO - 'TERM': 'linux', 18:29:55 INFO - 'TMOUT': '86400', 18:29:55 INFO - 'USER': 'cltbld', 18:29:55 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:55 INFO - '_': '/tools/buildbot/bin/python'} 18:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:55 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 18:29:55 INFO - Cleaning up... 18:29:55 INFO - Return code: 0 18:29:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:29: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')]} 18:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29: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')]} 18:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:29:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:29:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:29:55 INFO - Using env: {'DISPLAY': ':0', 18:29:55 INFO - 'HOME': '/home/cltbld', 18:29:55 INFO - 'LANG': 'en_US.UTF-8', 18:29:55 INFO - 'LANGUAGE': 'en_US:en', 18:29:55 INFO - 'LOGNAME': 'cltbld', 18:29:55 INFO - 'MAIL': '/var/mail/cltbld', 18:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:55 INFO - 'MOZ_NO_REMOTE': '1', 18:29:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:55 INFO - 'NO_EM_RESTART': '1', 18:29:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:29:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:29:55 INFO - 'SHELL': '/bin/bash', 18:29:55 INFO - 'SHLVL': '1', 18:29:55 INFO - 'TERM': 'linux', 18:29:55 INFO - 'TMOUT': '86400', 18:29:55 INFO - 'USER': 'cltbld', 18:29:55 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:55 INFO - '_': '/tools/buildbot/bin/python'} 18:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:55 INFO - Downloading/unpacking psutil>=0.7.1 18:29: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 18:29: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 18:29: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 18:29: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 18:29: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 18:29: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 18:29:57 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:29:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:29:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:57 INFO - Installing collected packages: psutil 18:29:57 INFO - Running setup.py install for psutil 18:29:57 INFO - building 'psutil._psutil_linux' extension 18:29:57 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 18:29:57 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 18:29:57 INFO - building 'psutil._psutil_posix' extension 18:29:57 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 18:29:57 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 18:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:57 INFO - Successfully installed psutil 18:29:57 INFO - Cleaning up... 18:29:57 INFO - Return code: 0 18:29:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:29: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')]} 18:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29: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')]} 18:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:29:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:29:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:29:57 INFO - Using env: {'DISPLAY': ':0', 18:29:57 INFO - 'HOME': '/home/cltbld', 18:29:57 INFO - 'LANG': 'en_US.UTF-8', 18:29:57 INFO - 'LANGUAGE': 'en_US:en', 18:29:57 INFO - 'LOGNAME': 'cltbld', 18:29:57 INFO - 'MAIL': '/var/mail/cltbld', 18:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:57 INFO - 'MOZ_NO_REMOTE': '1', 18:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:57 INFO - 'NO_EM_RESTART': '1', 18:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:29:57 INFO - 'PWD': '/builds/slave/test-pgo', 18:29:57 INFO - 'SHELL': '/bin/bash', 18:29:57 INFO - 'SHLVL': '1', 18:29:57 INFO - 'TERM': 'linux', 18:29:57 INFO - 'TMOUT': '86400', 18:29:57 INFO - 'USER': 'cltbld', 18:29:57 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:57 INFO - '_': '/tools/buildbot/bin/python'} 18:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:29:58 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 18:29:58 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 18:29:58 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 18:29:58 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 18:29:58 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 18:29:58 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 18:29:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:29:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:29:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:29:59 INFO - Installing collected packages: mozsystemmonitor 18:29:59 INFO - Running setup.py install for mozsystemmonitor 18:30:00 INFO - Successfully installed mozsystemmonitor 18:30:00 INFO - Cleaning up... 18:30:00 INFO - Return code: 0 18:30:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:30: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')]} 18:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:30:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:30:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:00 INFO - Using env: {'DISPLAY': ':0', 18:30:00 INFO - 'HOME': '/home/cltbld', 18:30:00 INFO - 'LANG': 'en_US.UTF-8', 18:30:00 INFO - 'LANGUAGE': 'en_US:en', 18:30:00 INFO - 'LOGNAME': 'cltbld', 18:30:00 INFO - 'MAIL': '/var/mail/cltbld', 18:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:00 INFO - 'MOZ_NO_REMOTE': '1', 18:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:00 INFO - 'NO_EM_RESTART': '1', 18:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:00 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:00 INFO - 'SHELL': '/bin/bash', 18:30:00 INFO - 'SHLVL': '1', 18:30:00 INFO - 'TERM': 'linux', 18:30:00 INFO - 'TMOUT': '86400', 18:30:00 INFO - 'USER': 'cltbld', 18:30:00 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:00 INFO - '_': '/tools/buildbot/bin/python'} 18:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:01 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:30:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:30:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:30:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:02 INFO - Downloading docopt-0.6.1.tar.gz 18:30:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:30:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:02 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:02 INFO - Running setup.py install for blobuploader 18:30:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:02 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:30:02 INFO - Running setup.py install for requests 18:30:03 INFO - Running setup.py install for docopt 18:30:03 INFO - Successfully installed blobuploader requests docopt 18:30:03 INFO - Cleaning up... 18:30:03 INFO - Return code: 0 18:30:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30: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')]} 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:30:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:30:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:03 INFO - Using env: {'DISPLAY': ':0', 18:30:03 INFO - 'HOME': '/home/cltbld', 18:30:03 INFO - 'LANG': 'en_US.UTF-8', 18:30:03 INFO - 'LANGUAGE': 'en_US:en', 18:30:03 INFO - 'LOGNAME': 'cltbld', 18:30:03 INFO - 'MAIL': '/var/mail/cltbld', 18:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:03 INFO - 'MOZ_NO_REMOTE': '1', 18:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:03 INFO - 'NO_EM_RESTART': '1', 18:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:03 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:03 INFO - 'SHELL': '/bin/bash', 18:30:03 INFO - 'SHLVL': '1', 18:30:03 INFO - 'TERM': 'linux', 18:30:03 INFO - 'TMOUT': '86400', 18:30:03 INFO - 'USER': 'cltbld', 18:30:03 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:03 INFO - '_': '/tools/buildbot/bin/python'} 18:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:03 INFO - Running setup.py (path:/tmp/pip-4OLr2a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:03 INFO - Running setup.py (path:/tmp/pip-AzGg_b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:03 INFO - Running setup.py (path:/tmp/pip-gZjv1i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:03 INFO - Running setup.py (path:/tmp/pip-ErXvyR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:03 INFO - Running setup.py (path:/tmp/pip-p_bFXK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:03 INFO - Running setup.py (path:/tmp/pip-P_0fnk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:04 INFO - Running setup.py (path:/tmp/pip-z95o25-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:04 INFO - Running setup.py (path:/tmp/pip-92wWMl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:04 INFO - Running setup.py (path:/tmp/pip-Isjpff-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:04 INFO - Running setup.py (path:/tmp/pip-H7dWjk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:04 INFO - Running setup.py (path:/tmp/pip-QoAYAZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:04 INFO - Running setup.py (path:/tmp/pip-iW3_vZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:04 INFO - Running setup.py (path:/tmp/pip-5rI4bf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:04 INFO - Running setup.py (path:/tmp/pip-0PWq6E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:04 INFO - Running setup.py (path:/tmp/pip-5U6pas-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:04 INFO - Running setup.py (path:/tmp/pip-LC0qIN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:04 INFO - Running setup.py (path:/tmp/pip-MBdcRg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:04 INFO - Running setup.py install for manifestparser 18:30:04 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:30:04 INFO - Running setup.py install for mozcrash 18:30:05 INFO - Running setup.py install for mozdebug 18:30:05 INFO - Running setup.py install for mozdevice 18:30:05 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Running setup.py install for mozfile 18:30:05 INFO - Running setup.py install for mozhttpd 18:30:05 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Running setup.py install for mozinfo 18:30:05 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Running setup.py install for mozInstall 18:30:05 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Running setup.py install for mozleak 18:30:05 INFO - Running setup.py install for mozlog 18:30:05 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Running setup.py install for moznetwork 18:30:05 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:30:05 INFO - Running setup.py install for mozprocess 18:30:06 INFO - Running setup.py install for mozprofile 18:30:06 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozrunner 18:30:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozscreenshot 18:30:06 INFO - Running setup.py install for moztest 18:30:06 INFO - Running setup.py install for mozversion 18:30:06 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:06 INFO - Cleaning up... 18:30:06 INFO - Return code: 0 18:30:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30:06 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')]} 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:06 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')]} 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:30:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:30:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:06 INFO - Using env: {'DISPLAY': ':0', 18:30:06 INFO - 'HOME': '/home/cltbld', 18:30:06 INFO - 'LANG': 'en_US.UTF-8', 18:30:06 INFO - 'LANGUAGE': 'en_US:en', 18:30:06 INFO - 'LOGNAME': 'cltbld', 18:30:06 INFO - 'MAIL': '/var/mail/cltbld', 18:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:06 INFO - 'MOZ_NO_REMOTE': '1', 18:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:06 INFO - 'NO_EM_RESTART': '1', 18:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:06 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:06 INFO - 'SHELL': '/bin/bash', 18:30:06 INFO - 'SHLVL': '1', 18:30:06 INFO - 'TERM': 'linux', 18:30:06 INFO - 'TMOUT': '86400', 18:30:06 INFO - 'USER': 'cltbld', 18:30:06 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:06 INFO - '_': '/tools/buildbot/bin/python'} 18:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:06 INFO - Running setup.py (path:/tmp/pip-f9PwI_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:06 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)) 18:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:06 INFO - Running setup.py (path:/tmp/pip-kOpZr4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:06 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)) 18:30:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:06 INFO - Running setup.py (path:/tmp/pip-nfmEqh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:07 INFO - Running setup.py (path:/tmp/pip-dpAoKZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:07 INFO - Running setup.py (path:/tmp/pip-wxA4gS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:07 INFO - Running setup.py (path:/tmp/pip-7A6mon-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:07 INFO - Running setup.py (path:/tmp/pip-CoDKwQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:07 INFO - Running setup.py (path:/tmp/pip-zoG7bk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:07 INFO - Running setup.py (path:/tmp/pip-tJvx2x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:07 INFO - Running setup.py (path:/tmp/pip-VkplIU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:07 INFO - Running setup.py (path:/tmp/pip-yq1b4n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:07 INFO - Running setup.py (path:/tmp/pip-VEV8Db-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:07 INFO - Running setup.py (path:/tmp/pip-MlBemB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:07 INFO - Running setup.py (path:/tmp/pip-EaSO8W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:07 INFO - Running setup.py (path:/tmp/pip-jKfELS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:07 INFO - Running setup.py (path:/tmp/pip-yLVsUG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:07 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)) 18:30:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:07 INFO - Running setup.py (path:/tmp/pip-o4oxys-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:08 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)) 18:30:08 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)) 18:30:08 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)) 18:30:08 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)) 18:30:08 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)) 18:30:08 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)) 18:30:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:09 INFO - Downloading blessings-1.5.1.tar.gz 18:30:09 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:30:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:09 INFO - Installing collected packages: blessings 18:30:09 INFO - Running setup.py install for blessings 18:30:09 INFO - Successfully installed blessings 18:30:09 INFO - Cleaning up... 18:30:09 INFO - Return code: 0 18:30:09 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:30:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:30:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:30:10 INFO - Reading from file tmpfile_stdout 18:30:10 INFO - Current package versions: 18:30:10 INFO - argparse == 1.2.1 18:30:10 INFO - blessings == 1.5.1 18:30:10 INFO - blobuploader == 1.2.4 18:30:10 INFO - docopt == 0.6.1 18:30:10 INFO - manifestparser == 1.1 18:30:10 INFO - mozInstall == 1.12 18:30:10 INFO - mozcrash == 0.16 18:30:10 INFO - mozdebug == 0.1 18:30:10 INFO - mozdevice == 0.47 18:30:10 INFO - mozfile == 1.2 18:30:10 INFO - mozhttpd == 0.7 18:30:10 INFO - mozinfo == 0.9 18:30:10 INFO - mozleak == 0.1 18:30:10 INFO - mozlog == 3.1 18:30:10 INFO - moznetwork == 0.27 18:30:10 INFO - mozprocess == 0.22 18:30:10 INFO - mozprofile == 0.27 18:30:10 INFO - mozrunner == 6.11 18:30:10 INFO - mozscreenshot == 0.1 18:30:10 INFO - mozsystemmonitor == 0.0 18:30:10 INFO - moztest == 0.7 18:30:10 INFO - mozversion == 1.4 18:30:10 INFO - psutil == 3.1.1 18:30:10 INFO - requests == 1.2.3 18:30:10 INFO - wsgiref == 0.1.2 18:30:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30: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')]} 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2428c5c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ee6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a07c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, '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': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', '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 18:30:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:30:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:10 INFO - Using env: {'DISPLAY': ':0', 18:30:10 INFO - 'HOME': '/home/cltbld', 18:30:10 INFO - 'LANG': 'en_US.UTF-8', 18:30:10 INFO - 'LANGUAGE': 'en_US:en', 18:30:10 INFO - 'LOGNAME': 'cltbld', 18:30:10 INFO - 'MAIL': '/var/mail/cltbld', 18:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:10 INFO - 'MOZ_NO_REMOTE': '1', 18:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:10 INFO - 'NO_EM_RESTART': '1', 18:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:10 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:10 INFO - 'SHELL': '/bin/bash', 18:30:10 INFO - 'SHLVL': '1', 18:30:10 INFO - 'TERM': 'linux', 18:30:10 INFO - 'TMOUT': '86400', 18:30:10 INFO - 'USER': 'cltbld', 18:30:10 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:10 INFO - '_': '/tools/buildbot/bin/python'} 18:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:10 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)) 18:30:10 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)) 18:30:10 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)) 18:30:10 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)) 18:30:10 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)) 18:30:10 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)) 18:30:10 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)) 18:30:10 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)) 18:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:30:10 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)) 18:30:10 INFO - Cleaning up... 18:30:10 INFO - Return code: 0 18:30:10 INFO - Running post-action listener: _resource_record_post_action 18:30:10 INFO - Running post-action listener: _start_resource_monitoring 18:30:10 INFO - Starting resource monitoring. 18:30:10 INFO - ##### 18:30:10 INFO - ##### Running install step. 18:30:10 INFO - ##### 18:30:10 INFO - Running pre-action listener: _resource_record_pre_action 18:30:10 INFO - Running main action method: install 18:30:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:30:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:30:10 INFO - Reading from file tmpfile_stdout 18:30:10 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:30:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:30:10 INFO - Reading from file tmpfile_stdout 18:30:10 INFO - Output received: 18:30:10 INFO - Usage: mozinstall [options] installer 18:30:10 INFO - Options: 18:30:10 INFO - -h, --help show this help message and exit 18:30:10 INFO - -d DEST, --destination=DEST 18:30:10 INFO - Directory to install application into. [default: 18:30:10 INFO - "/builds/slave/test-pgo"] 18:30:10 INFO - --app=APP Application being installed. [default: firefox] 18:30:10 INFO - mkdir: /builds/slave/test-pgo/build/application 18:30:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:30:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:30:25 INFO - Reading from file tmpfile_stdout 18:30:25 INFO - Output received: 18:30:25 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:30:25 INFO - Running post-action listener: _resource_record_post_action 18:30:25 INFO - ##### 18:30:25 INFO - ##### Running run-tests step. 18:30:25 INFO - ##### 18:30:25 INFO - Running pre-action listener: _resource_record_pre_action 18:30:25 INFO - Running main action method: run_tests 18:30:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:30:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:30:25 INFO - Python 2.7.3 18:30:25 INFO - Return code: 0 18:30:25 INFO - grabbing minidump binary from tooltool 18:30:25 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')]} 18:30:25 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 0x13e4d70>, '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 0x13e5b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e5fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:30:25 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 18:30:25 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 18:30:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:25 INFO - Return code: 0 18:30:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:30:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:30:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:30:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:30:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:30:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:30:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost 18:30:25 INFO - Using env: {'DISPLAY': ':0', 18:30:25 INFO - 'HOME': '/home/cltbld', 18:30:25 INFO - 'LANG': 'en_US.UTF-8', 18:30:25 INFO - 'LANGUAGE': 'en_US:en', 18:30:25 INFO - 'LOGNAME': 'cltbld', 18:30:25 INFO - 'MAIL': '/var/mail/cltbld', 18:30:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:30:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:25 INFO - 'MOZ_NO_REMOTE': '1', 18:30:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:25 INFO - 'NO_EM_RESTART': '1', 18:30:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:25 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:30:25 INFO - 'SHELL': '/bin/bash', 18:30:25 INFO - 'SHLVL': '1', 18:30:25 INFO - 'TERM': 'linux', 18:30:25 INFO - 'TMOUT': '86400', 18:30:25 INFO - 'USER': 'cltbld', 18:30:25 INFO - 'XDG_SESSION_COOKIE': '5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513', 18:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:25 INFO - '_': '/tools/buildbot/bin/python'} 18:30:25 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost'] with output_timeout 3600 18:30:25 INFO - mozversion INFO | application_buildid: 20151123173004 18:30:25 INFO - mozversion INFO | application_changeset: 65cd2c7696e438c74bfbb0ad40e18f411dbead38 18:30:25 INFO - mozversion INFO | application_display_name: Nightly 18:30:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:25 INFO - mozversion INFO | application_name: Firefox 18:30:25 INFO - mozversion INFO | application_remotingname: firefox 18:30:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:30:25 INFO - mozversion INFO | application_vendor: Mozilla 18:30:25 INFO - mozversion INFO | application_version: 45.0a1 18:30:25 INFO - mozversion INFO | platform_buildid: 20151123173004 18:30:25 INFO - mozversion INFO | platform_changeset: 65cd2c7696e438c74bfbb0ad40e18f411dbead38 18:30:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:30:25 INFO - mozversion INFO | platform_version: 45.0a1 18:30:25 INFO - 2015-11-23 18:30:25,476 DEBUG : using testdate: 1448332225 18:30:25 INFO - 2015-11-23 18:30:25,476 DEBUG : actual date: 1448332225 18:30:25 INFO - 2015-11-23 18:30:25,561 INFO : starting webserver on 'localhost:48534' 18:30:25 INFO - 2015-11-23 18:30:25,562 INFO : Starting test suite talos-linux64-ix-024 18:30:25 INFO - 2015-11-23 18:30:25,562 INFO : Starting test dromaeo_css 18:30:25 INFO - 2015-11-23 18:30:25,562 DEBUG : operating with platform_type : linux_ 18:30:25 INFO - 2015-11-23 18:30:25,563 INFO : Initialising browser for dromaeo_css test... 18:30:25 INFO - 2015-11-23 18:30:25,579 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDwtCO2/profile http://localhost:48534/getInfo.html 18:30:29 INFO - 2015-11-23 18:30:29,564 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:30:29 INFO - 2015-11-23 18:30:29,564 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:30:29 INFO - 2015-11-23 18:30:29,564 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:30:29 INFO - 2015-11-23 18:30:29,564 DEBUG : BROWSER_OUTPUT: __metrics 18:30:29 INFO - 2015-11-23 18:30:29,686 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:30 INFO - 2015-11-23 18:30:30,697 INFO : Browser initialized. 18:30:30 INFO - 2015-11-23 18:30:30,697 INFO : Running cycle 1/1 for dromaeo_css test... 18:30:30 INFO - 2015-11-23 18:30:30,698 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDwtCO2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:30:42 INFO - 2015-11-23 18:30:42,482 DEBUG : BROWSER_OUTPUT: RSS: Main: 140472320 18:30:42 INFO - 2015-11-23 18:30:42,482 DEBUG : BROWSER_OUTPUT: 18:31:34 INFO - 2015-11-23 18:31:34,120 DEBUG : BROWSER_OUTPUT: 1448332294119 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] 18:33:42 INFO - 2015-11-23 18:33:42,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48534/tests/dromaeo/cssquery-ext.html) 18:33:43 INFO - 2015-11-23 18:33:43,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 232075264 18:33:43 INFO - 2015-11-23 18:33:43,749 DEBUG : BROWSER_OUTPUT: 18:36:46 INFO - 2015-11-23 18:36:46,072 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/dromaeo/cssquery-ext.html (next: http://localhost:48534/tests/dromaeo/cssquery-jquery.html) 18:36:46 INFO - 2015-11-23 18:36:46,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 217468928 18:36:46 INFO - 2015-11-23 18:36:46,641 DEBUG : BROWSER_OUTPUT: 18:39:48 INFO - 2015-11-23 18:39:48,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48534/tests/dromaeo/cssquery-mootools.html) 18:39:49 INFO - 2015-11-23 18:39:49,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 214962176 18:39:49 INFO - 2015-11-23 18:39:49,107 DEBUG : BROWSER_OUTPUT: 18:42:51 INFO - 2015-11-23 18:42:51,052 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48534/tests/dromaeo/cssquery-prototype.html) 18:42:51 INFO - 2015-11-23 18:42:51,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 201068544 18:42:51 INFO - 2015-11-23 18:42:51,613 DEBUG : BROWSER_OUTPUT: 18:45:54 INFO - 2015-11-23 18:45:54,113 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48534/tests/dromaeo/cssquery-yui.html) 18:45:54 INFO - 2015-11-23 18:45:54,686 DEBUG : BROWSER_OUTPUT: RSS: Main: 202719232 18:45:54 INFO - 2015-11-23 18:45:54,686 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,251 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/dromaeo/cssquery-yui.html (next: http://localhost:48534/tests/dromaeo/cssquery-dojo.html) 18:48:57 INFO - 2015-11-23 18:48:57,501 DEBUG : BROWSER_OUTPUT: RSS: Main: 280846336 18:48:57 INFO - 2015-11-23 18:48:57,501 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,501 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:48:57 INFO - 2015-11-23 18:48:57,501 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:48:57 INFO - 2015-11-23 18:48:57,502 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:48:57 INFO - 2015-11-23 18:48:57,502 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:48:57 INFO - 2015-11-23 18:48:57,502 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3186.697263759943;3234.191452136966;3296.802191868488;3301.2077101495634;3319.087251005974;5235.790568377261;5318.813841515548;5366.302380690509;5376.274202982601;5471.6443431176995;5320.308359913215;5371.274877891484;5389.8113566025195;5394.91907621386;5477.4796394342575;4895.755212239392;4908.656394052417;4924.482929292427;4960.156773348539;5013.949860501385;4770.928436073462;4985.052839960414;4992.226204938241;5022.82420115296;5045.041442126003;4873.098476781789;4963.528464795839;5023.34696489454;5043.243513472972;5044.47033061527;4909.263610458424;4922.433920099199;4925.21196608541;4959.8016079356785;4972.0801651694555;4867.561919427233;4923.975380123113;4944.826931057396;4996.550310472038;5009.499050094998;5156.278121063016;5220.869478263074;5233;5247.081760691902;5268.947310526884;5935.020721580934;5951.137085122677;5951.464368206839;5952.613080149819;5953.017752070903;5734.598578099534;5797.246357973479;5856.238688970449;5975.312839023524;5983.311919129313;5594.440555944414;5637.295338082739;5643.068893632546;5645.943540564542;5652.463016013513;6075.939240607538;6076.513878889678;6092.3603021682975;6100;6103.489197737777;5639.43605639441;5723.971380143073;5730.140478928194;5740.9953258180485;5910.113482977502;5993;5996.1005849122985;6003;6009.038553831366;6041;5920.2007728956905;5924.644521328734;5965.701714914237;5970.701464926737;5973.283206015306;5643.661380317194;5675.148727690879;5753.568482363839;5759.827205183768;5947.31605865331;4517.638588912945;4527.7736113194205;4536.432945881765;4561.840335588238;4563.726176429424;3983.5418926823786;3986.142979259473;3993.5008123984503;4031.4154447604933;4058.857939972087;5340.359156901197;5422.728863556807;5467.0705979983495;5568.248286481355;5575.1915972184615;5821.301443826768;5941.257342832146;5942.524598032147;5944.494717948936;5954.07711804671;5594.272744543265;5599.832005039855;5603.915941260884;5604.8038318658655;5608.803691870847;4959.305697202394;5008.499150084962;5055.191169412949;5104.3109180260935;5121.795128194905;6065.5754097212775;6066.908996364969;6075;6077.969610151921;6082.665453400105;5827.825165245049;5883.470487656132;5883.705814709162;5892.175095486549;5896.233489646319;5764.394738552392;5894.263217097863;6010.1585777991995;6067.908981365194;6113.969430152821;5832.066869300891;5842.5325973921135;5873.353931067493;5880.911786323294;5890.2342695449315;5692.914606280826;5946.881062378641;5948.4943779779205;5949.851253718692;5950;5686.317641883001;5688.886222275614;5759.798407055733;5819.679917604572;5867.413258674099;5769.91345129815;5786.33457152416;5800.8259752207505;5803.738831752666;5805.970970145122;5539.806106786243;5723.456271654186;5733.111367738007;5737.483626473638;5738.311402631711;5636.464535869086;5648.689322087324;5675.829725108253;5788.102844059177;5821.970890145692;5637.774489020558;5671.773129074791;5691.117876729114;5693.971530142323;5746.13807928814 18:48:57 INFO - 2015-11-23 18:48:57,503 DEBUG : BROWSER_OUTPUT: |1;ext.html;551.7296524702897;599.0953659973441;599.5533327670885;606.0636316890403;610.8106486989032;31376.372472550564;39316.21367572647;39643;39794.8010259949;39916.40125398114;43265.78367108168;43296.567034329644;43741;43771.34342984858;43815.12369752603;7378.077740282465;7609.353204977556;8532.57337133144;8751;9990.850137247948;10082.143017843493;10189.490525473697;10197.541110650038;10198.439085850276;10200.693979180636;10139.290249682525;10244.487775611227;10251.641192558262;10254.128399086087;10266.38401695897;7632.198619144993;7813.76558703237;10290.279680422373;10491.895081049168;10518.105960993249;10015.69952901414;10095.44475053879;10170.23723220754;10172.542235599416;10184.388936663823;40242.39636405456;40282.79858600718;40651.7967410164;40740.1851962962;40756.38865417021;3184.952225716616;3223.468127758917;3256.218507558193;3304;3592.892213233607;29893.551596726065;30379;30380;30704;31524.842375788205;3764;3866.5553461352024;3891.455196272496;3940.7438516496622;4018.57804930481;432699;433819;436277;437812;441367.9999999968;60647.696761516796;61169.35383077116;61503;61589;61592;57314.71342643344;57762.71118644464;63392.68303658452;63719.36280637134;63730;15378.692426151414;15580.532584022498;15639.452619158275;15642.296096675676;15673.451429200151;9862.408255504693;9874.160696340748;9885.25860560461;9886.15967642744;9944;20049.09779059946;20225.674047484434;20236.898815506123;20237.595248094956;20263.493412664506;7856.310893157998;7903.407244456689;7940.563269020259;8056.274935255738;8058.35533157346;926.1201858234704;929.5212965322864;969.3989726369696;985.9063388978036;1073.7852429514128;28703;28872.42255154885;28991.710082898902;29268;29403.264918376783;1038.133158812385;1052.58422922945;1114.7658991611852;1120.1207052463776;1157.5948418053613;14955;15096.849031509764;15115.848841511664;15212.923935380251;15319;324759;329193;329409;330052;330686;52508.47491525036;53330;53389;53401;53496.465035349145;1333.2866916199787;1335.8196643453205;1343.1538130977617;1345.9125156864773;1355.620426280643;27680.44639107247;28378;28424.005159820135;28608.856955715088;28895.711042889303;47229.52770472251;47614.76192619015;47653.28520072271;47677;47735.28397073977;306248;306688;307683;308428;309014;32297.838510807294;32535.18662033468;32596.34807303888;32811.671883281815;32960.340793183525;42305.5769442314;42525.5747442534;42847;43293.3505997404;43857.56142438535;35685.46471802977;35817.283654326246;36301.636983629825;37077.258454831295;37549.81225093857;27953.44093118167;30082.09753707392;32562.51156232611;35406.822965885;35799;33284.33431331408;34092.65907340994;34604.48093278653;34639.826800865834;34641.82679086588;29029.7097029027;30439;35919.28161436809;37444;38185.6181438182;25250.242492725247;25844.612330814678;28091.578626320214;28201.858990704914;28412.715872841833 18:48:57 INFO - 2015-11-23 18:48:57,504 DEBUG : BROWSER_OUTPUT: |2;jquery.html;355.14055984517466;356.64691954964593;356.9625189355116;359.288608555061;359.46080878681977;14184.36170372333;14279.857201428009;16164.757528637065;16606.584835379108;17417.564560885985;15164.84835151648;16351.918240408811;16354.7546786798;16458;17476.126193690296;5215.660982036165;5356.892862142755;5363.249145119687;5363.731813409314;5366.275552800361;3930.66589339905;3946.151577410856;5348.144296912495;5410.83767486974;5891.086881533369;6035.336113027563;6063.090536419529;6101.572889897709;6115.113308570255;6119.66341851198;5098.311727916721;5231.319928409321;5238.4499627539135;5240.81657142002;5999.910001349983;4972.5276098770555;5017.322661440695;5025.1708468102715;5036.320096786923;5259.631825772198;11596.94201528987;11764.529418823236;11814.527418903319;11824.172307938448;11916.821247681291;1970.7142464342733;2008.3974807557674;2080.750309962799;2097.3812725245934;2119.4065661614773;9575.760605984906;9762.170215531618;9953.25350598708;11917.284962902253;12620.990320774315;1777.3779177287975;1798.7122060470392;1823.4985379020743;1855.434092601756;1859.1447933950265;58393.41606583922;61985;62929.37070629281;63480.68259658718;63786.362136378506;9468.195203407788;9672.177864881423;9881.901180988098;9996;10047.899521004696;12988.415521301564;12992.415341309475;12998.870011299956;13000.08999370035;13018.088733788543;7237.276272372721;7249.383802376857;7370.96314518424;7485.737999170003;7504.474686771982;7326.230745771723;7326.743563975236;7366.373858222111;7406.962965185247;7413.1104267488245;4200.474940632421;4236.957630423718;4283.271843786564;4287.957120428819;4303.483581970184;1646.588352911772;1666.7916510436196;1697.5416637508051;1702.5403141152067;1703.4548944337694;887.8401887660154;896.112848280211;970.9126178643955;994.980145351021;995.5022488755623;16164.919175404048;16748;16759.58101047459;17068.146592670317;17185.7422138668;913.9862902056475;919.7516670498928;919.8712180294764;923.5613083785282;989.6437282578266;9224.123708247706;9478.194353479894;9618.134367906921;9950;9982.151517121127;564670;566524;569822.1508892429;577266;577695;78574;78976.60511697405;79982.20017799865;82275.5886220565;82979.17020829715;1112.6662001399548;1133.3426612564692;1136.5510623303915;1150.1144119028302;1160.7504386556932;15379.538613841603;15379.538613841603;15432.459863904709;15538;15729.292181851615;33877;34325.48511772375;34524;35065.123371915906;35513.64486355103;44236;44645.33032004457;45756.31365529453;46068;46111;9223.123803238681;9307.488088155216;9321.627134914528;9424.481653509123;9525.333226674065;16961;17372.13139343053;17424.390146344816;17659.823401765818;17691.911540442215;11110.16673749456;12323.876761232274;13900.721985560434;14148.575542733735;14529.273536323353;11645.59240426581;13392.464301427834;13402;14296.785548216993;14361.497347592784;14272.357743901757;14519.78220326717;14671;14825.481108161162;14847.331870065656;8791.824163516565;8860.556972151495;8896.822063558822;9272.258219342571;9331.58007889633;10513.737156571147;14374.281285935871;14428.062175958528;14579.708405832036;14689.338979746151 18:48:57 INFO - 2015-11-23 18:48:57,508 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1278.9424475898584;1666.2668425892607;1720.7669133930526;1724.4998950304412;1764.0474143962253;6885.449164066875;7377.889331660022;7635.160132385433;7643.541387516746;7644.197359277279;7614.73348432805;7721.806954826118;7743.7676869694;7753.689852405912;7753.92246077539;1947.4936516505668;1953.150379584885;1969.3008981811465;2162.5566758814443;2187.136081247906;2212.236778311488;2215.501512159767;2232;2233.4416395901026;2238.149503188786;2141.7858214178614;2141.9464513387134;2262.3891549281684;2268.5689718953427;2272.250157448038;1936.9128389222449;1971.2016633263552;1973.1416833677397;2145.1419432227076;2200.504886400563;2177.934661960144;2180.7819218078225;2185.5628874225217;2185.7486389065302;2186.0162926683056;7585.506942048749;7718.807029824243;7727.536347819093;7731.690732370698;7739.187385324516;4582.587567118942;4701.153792317382;4705.247160454311;4709.411323584552;4711.24620060792;2937.089502254308;2978.37454134632;3046.2536678513684;3055.847207639609;3057.250973511482;927.1701826864986;931.8462453695132;954.5847556312957;955.3742298794301;959.9088086631759;10683.358998460117;10713.625023124154;10727.946360268226;10728.088112510446;10745.892541074645;4236.131593023423;4266.466691663542;4303.311470164821;4320.503142138694;4364.912701745948;9210.125038121369;9213.63145474187;9228.40015398997;9228.723138305862;9229.723108306762;4794.688345257547;4820.517948205152;4843.685160464559;4847.224444088929;4849.75751212445;4387.824487020547;4388.8902777430185;4394.36281739146;4400.669949753781;4400.9437734943385;3687.8403625233577;3718.4236443351324;3733.6826369758874;3742.045778326509;3748.493953316322;1165.6386520178771;1168.2172944127456;1176.7352345722315;1184.029096141156;1187.2105050141558;992.5583115513527;992.5979978108877;1229.9852621587227;1323.4970711129708;1353.607453838376;7299.70801167958;7348.52234604749;7395.8151046224275;7404.925950740531;7408.85182296351;466.60571816814735;469.0852836967926;473.5453964194343;478.71755664158314;486.4624589828189;5459.153831156098;5614.9719251403485;5947.613405128653;6064.878702425926;6067.969660151671;9168.129027742354;9188;9440.811183776423;9456.90543094574;9486.051394860597;9229.446233225894;9250.21373183287;9354.345195836226;9363.953180234055;9392.62429502826;7001.649917504104;7004.509684322151;7038.788836334918;7155.3202445767565;7269.34575888162;7157.677904494414;7247.347738703331;7256.492045556972;7261.927380726125;7263.4915555910575;8212.096669366485;8411.4112012161;8419.326453883798;8420.957895210484;8422.157784221528;8360.623771930155;8580.957095214484;8591.226789588967;8706.390552661253;8716.564171791511;6885.208201056742;6913.89629155553;6952.965235173994;6969.233384327618;6978;7530.698772049276;7562.659680314509;7569.3566046886635;7580.507267027626;7593.620318984139;6981.0924579804305;7124.287571242834;7196.280371962762;7219.133703955559;7239.8914016290855;7063.293670633101;7123.608201548965;7171.533850299714;7182.317679820409;7214.422846172399;6714.630695311805;6814.318568143146;6841.760538381132;6862.588244705334;6875.1749790025515;6306.400891915261;6330.177076979963;6341.397567231105;6344.651044192429;6353.523485738496;5835.591508594358;5953.374895635896;6491.740330386731;6555.868882622416;6603.636799976048 18:48:57 INFO - 2015-11-23 18:48:57,508 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.4902173207589;340.0597348331862;341.1675511751328;341.25946695670393;342.4982400782853;5992.610480318782;6017.458428741413;6039.245094363205;6059.303180134275;6070.9392906070925;5708.657480551159;5773.249477567921;5859.267591551056;5902.232709747726;5991.550633702479;4572.5427457254345;4581.839635612754;4596.609288210506;4596.862094137181;4605.102005109005;4427;4596.816127354902;4597.264437689971;4598.287265473857;4603.125406172817;4446.933296000562;4459.15276097542;4577.633789296865;4584.770761461914;4605.654575906804;4574;4577.9771101144615;4598.0344127733215;4598.97700511497;4599.954000459986;4018.7789671568053;4027.7381970172137;4028;4028.0735430850777;4032.072623296658;5524.364698059734;5625.831225063254;5648.152777083429;5655.010373184677;5672.631278966854;5355.598330125217;5356.571474282048;5358.196270559409;5362.973185134049;5372.811951581715;6361.650109243989;6362.5546211765195;6362.968185159091;6373.808785736435;6374.458171055466;4483.708558943658;4484.035932274544;4484.461864576239;4498.842540511099;4510.954890451087;6437.485001199892;6603.7688680895935;6612;6635.601863888134;6639.933600664028;4467.664925130628;4494.213512635276;4503.797329120198;4506.797194126272;4511.300748384006;4560.908781824345;4562.270036794097;4566.611837993742;4567.36056952027;4568.428946381702;5277.076511610453;5290.735463226823;5293.655912365684;5295.391030031596;5299;6268.561200715906;6412.198475190601;6433.678316084177;6435.22777266731;6442.452391546677;1585.9207039647972;1595.4285029246182;1612.1536193498507;1612.8145263294637;1612.9516114516584;1059.7138772531373;1060.4061725433783;1068.7274744940146;1078.3907092492716;1080.189857606795;6215.844603884849;6223.937760622336;6225.564210505312;6239.06414037898;6244.344343843922;6555.475561955032;6561.048647946021;6562.507811914125;6563.901541476882;6564.671766411756;6011.48902343297;6119;6138.202033735586;6303.558750887486;6306.243250809932;3560.572731272212;3592.0660628236847;3595.1191957970464;3601.405768048243;3602.081469225383;6903.240643529208;6903.965480172667;6907.102076729993;6909;6911.136107986502;6656.733730650816;6814.318568143245;6824;6830.316968303229;6831.487638427137;6884.449244060564;6890.586564806027;6892.793216203522;6898.206706228748;6899.965500172467;6574.967125164344;6617;6622.07290979273;6623;6632.867342653217;6413.16628838248;6584.637844918576;6593.868122637617;6595.736170553124;6601.966990165019;6880.621565813928;6884.621345826027;6887.7244910203035;6892.758753443605;6917.066196063467;4540.545945405565;4565.885852853572;4567.06375193078;4569.36028955953;4577.954220457886;6509.006901103739;6557.147570815763;6592.208934927839;6597.340265973365;6606.966965165144;6402.775902843378;6405.3594640535575;6408.166938297992;6408.551401401857;6429.453496452854;6421.422072013541;6449.097126402401;6452.516061295328;6453.9354606453335;6459.806205813833;6190.7214175363115;6327.303996560283;6332.810015699536;6337.112804207322;6341.239051313687;6481.611103333815;6485.156929599122;6486.286508484157;6490.48076153916;6493.740250389931;6285.497160227263;6288.490762217127;6295.244570651551;6300.842478938063;6301.684915754102 18:48:57 INFO - 2015-11-23 18:48:57,508 DEBUG : BROWSER_OUTPUT: |5;yui.html;697.1355519156244;712.8716830970424;715.749487679312;750.2347605442448;897.0446474504652;13568.118072325293;13883.861161388382;13899.235542045184;13923.651908702263;13950.302484875767;12929.418176182071;14509.129452232874;14624.561263162122;14685.632859178499;14700.705985880277;684.7192651013086;688.4079691465361;801.2748462641305;813.3655748516137;814.1980149552686;778.8559116563425;809.6761295481825;811.8335741173075;815.8286759780424;817.5544328341039;859.3726579596898;861.5778268648348;862.0474375814729;862.1421685422981;862.3144600042981;710.8222944263935;714.1287629092499;853.6841368693592;853.9871901921476;859.6819176904523;626.580191271849;626.7931582577738;627.7645882793952;629.7732816186169;631.4758750237293;14134.36395362211;14303.499377521737;14313.355898984571;14318.71362572753;14339.641508962255;12643.114981951267;13018.609441716764;13037.608871733863;13411.932940335333;13427.462901484027;9767.355890037297;9957.601695932153;10220.437875916821;10257.89742102577;10425.113865321457;3663.6336366363416;3707.888763337104;3750.5311836020496;3779.281936432069;3829.425586162098;48256.27615585769;49849.750751246014;49854.50145498535;49896.25155622632;49956;9814.362066465657;9824.65613703517;9981.201503879673;10088.949555252177;10539.789204216026;19057.428277151706;19197.232110715417;19199.712004319947;19224;19257.51856203606;7334.083239595051;7374.778756637309;7449.143348514882;7467.178610352857;7470.178280389153;4299.161663475592;4393.0774537347515;4415.580519850609;4421.602055814992;4473.507914129443;3110.8133511989354;3112.3464072544566;3133.5613014178034;3141.340318533088;3164.7784655074383;526.2369564132024;535.5528134008147;543.2964311216983;555.2393221286782;555.9749811258503;507.26446652353496;594.9113122984927;610.9003793172272;617.3980369140143;618.1994317359031;8051.194880511902;8068.7175948841505;8212.384071194565;8454.915450845536;8633.956830215935;497.76604995651985;538.1550964984937;549.8268045565634;550.1829782772579;670.9127813384229;3643.508126402902;3679.19057807282;3757.5866654667966;3787.6780473659496;3886.8056597170594;57974.420255796904;58225;58230.12654810181;58290;58482.415175848546;52297.215541766906;53741.1938820918;54060;54843;54857.45142548603;46536;46892.2966155508;47256.763716181886;47383.28925066127;47394;7997.720079797179;8064.314533264738;8251.628676709443;8319.750407487785;8402.117777633504;35323.82338088293;35436.82281588575;35811.3884875176;35917.1020724484;36332;42129.78935105305;42184.789076054425;45402.31896521489;46518.767406162755;46592.30111548397;10573.312734672221;10763;10766.838497422388;10767.676969690921;10880.075193608633;11347.092232621535;11635.825462617899;12063.336516491678;12076.21504602198;12095.81856272139;10894.509747061244;11471.598494053002;11529.538818447169;11705.941470292593;11741.471633776671;10570.41862697559;10614.73463163427;10619.893801061891;10625.468726563795;10755;10663.786724265627;11021;11059.723506912069;11241.887581124083;11270.718232044264;9382.390144640576;9449.385789923634;9475.147236748726;9575.569099390683;9620.278479113955;8570.785730356542;8800.251978581893;8994.55027248648;9184.357095003286;9407.341486095907 18:48:57 INFO - 2015-11-23 18:48:57,508 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:48:57 INFO - 2015-11-23 18:48:57,508 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:48:57 INFO - 2015-11-23 18:48:57,508 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,959 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333337498__endTimestamp 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,509 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5453.32 Median:5675.15 stddev:622.32 (11.0%) stddev-sans-first:598.29 18:48:57 INFO - 2015-11-23 18:48:57,510 DEBUG : BROWSER_OUTPUT: Values: 3186.7 3234.2 3296.8 3301.2 3319.1 5235.8 5318.8 5366.3 5376.3 5471.6 5320.3 5371.3 5389.8 5394.9 5477.5 4895.8 4908.7 4924.5 4960.2 5013.9 4770.9 4985.1 4992.2 5022.8 5045.0 4873.1 4963.5 5023.3 5043.2 5044.5 4909.3 4922.4 4925.2 4959.8 4972.1 4867.6 4924.0 4944.8 4996.6 5009.5 5156.3 5220.9 5233.0 5247.1 5268.9 5935.0 5951.1 5951.5 5952.6 5953.0 5734.6 5797.2 5856.2 5975.3 5983.3 5594.4 5637.3 5643.1 5645.9 5652.5 6075.9 6076.5 6092.4 6100.0 6103.5 5639.4 5724.0 5730.1 5741.0 5910.1 5993.0 5996.1 6003.0 6009.0 6041.0 5920.2 5924.6 5965.7 5970.7 5973.3 5643.7 5675.1 5753.6 5759.8 5947.3 4517.6 4527.8 4536.4 4561.8 4563.7 3983.5 3986.1 3993.5 4031.4 4058.9 5340.4 5422.7 5467.1 5568.2 5575.2 5821.3 5941.3 5942.5 5944.5 5954.1 5594.3 5599.8 5603.9 5604.8 5608.8 4959.3 5008.5 5055.2 5104.3 5121.8 6065.6 6066.9 6075.0 6078.0 6082.7 5827.8 5883.5 5883.7 5892.2 5896.2 5764.4 5894.3 6010.2 6067.9 6114.0 5832.1 5842.5 5873.4 5880.9 5890.2 5692.9 5946.9 5948.5 5949.9 5950.0 5686.3 5688.9 5759.8 5819.7 5867.4 5769.9 5786.3 5800.8 5803.7 5806.0 5539.8 5723.5 5733.1 5737.5 5738.3 5636.5 5648.7 5675.8 5788.1 5822.0 5637.8 5671.8 5691.1 5694.0 5746.1 18:48:57 INFO - 2015-11-23 18:48:57,510 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52073.95 Median:34348.57 stddev:95384.27 (277.7%) stddev-sans-first:95573.40 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: Values: 551.7 599.1 599.6 606.1 610.8 31376.4 39316.2 39643.0 39794.8 39916.4 43265.8 43296.6 43741.0 43771.3 43815.1 7378.1 7609.4 8532.6 8751.0 9990.9 10082.1 10189.5 10197.5 10198.4 10200.7 10139.3 10244.5 10251.6 10254.1 10266.4 7632.2 7813.8 10290.3 10491.9 10518.1 10015.7 10095.4 10170.2 10172.5 10184.4 40242.4 40282.8 40651.8 40740.2 40756.4 3185.0 3223.5 3256.2 3304.0 3592.9 29893.6 30379.0 30380.0 30704.0 31524.8 3764.0 3866.6 3891.5 3940.7 4018.6 432699.0 433819.0 436277.0 437812.0 441368.0 60647.7 61169.4 61503.0 61589.0 61592.0 57314.7 57762.7 63392.7 63719.4 63730.0 15378.7 15580.5 15639.5 15642.3 15673.5 9862.4 9874.2 9885.3 9886.2 9944.0 20049.1 20225.7 20236.9 20237.6 20263.5 7856.3 7903.4 7940.6 8056.3 8058.4 926.1 929.5 969.4 985.9 1073.8 28703.0 28872.4 28991.7 29268.0 29403.3 1038.1 1052.6 1114.8 1120.1 1157.6 14955.0 15096.8 15115.8 15212.9 15319.0 324759.0 329193.0 329409.0 330052.0 330686.0 52508.5 53330.0 53389.0 53401.0 53496.5 1333.3 1335.8 1343.2 1345.9 1355.6 27680.4 28378.0 28424.0 28608.9 28895.7 47229.5 47614.8 47653.3 47677.0 47735.3 306248.0 306688.0 307683.0 308428.0 309014.0 32297.8 32535.2 32596.3 32811.7 32960.3 42305.6 42525.6 42847.0 43293.4 43857.6 35685.5 35817.3 36301.6 37077.3 37549.8 27953.4 30082.1 32562.5 35406.8 35799.0 33284.3 34092.7 34604.5 34639.8 34641.8 29029.7 30439.0 35919.3 37444.0 38185.6 25250.2 25844.6 28091.6 28201.9 28412.7 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29409.51 Median:4218.72 stddev:93346.36 (2212.7%) stddev-sans-first:93582.72 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: Values: 355.1 356.6 357.0 359.3 359.5 14184.4 14279.9 16164.8 16606.6 17417.6 15164.8 16351.9 16354.8 16458.0 17476.1 5215.7 5356.9 5363.2 5363.7 5366.3 3930.7 3946.2 5348.1 5410.8 5891.1 6035.3 6063.1 6101.6 6115.1 6119.7 5098.3 5231.3 5238.4 5240.8 5999.9 4972.5 5017.3 5025.2 5036.3 5259.6 11596.9 11764.5 11814.5 11824.2 11916.8 1970.7 2008.4 2080.8 2097.4 2119.4 9575.8 9762.2 9953.3 11917.3 12621.0 1777.4 1798.7 1823.5 1855.4 1859.1 58393.4 61985.0 62929.4 63480.7 63786.4 9468.2 9672.2 9881.9 9996.0 10047.9 12988.4 12992.4 12998.9 13000.1 13018.1 7237.3 7249.4 7371.0 7485.7 7504.5 7326.2 7326.7 7366.4 7407.0 7413.1 4200.5 4237.0 4283.3 4288.0 4303.5 1646.6 1666.8 1697.5 1702.5 1703.5 887.8 896.1 970.9 995.0 995.5 16164.9 16748.0 16759.6 17068.1 17185.7 914.0 919.8 919.9 923.6 989.6 9224.1 9478.2 9618.1 9950.0 9982.2 564670.0 566524.0 569822.2 577266.0 577695.0 78574.0 78976.6 79982.2 82275.6 82979.2 1112.7 1133.3 1136.6 1150.1 1160.8 15379.5 15379.5 15432.5 15538.0 15729.3 33877.0 34325.5 34524.0 35065.1 35513.6 44236.0 44645.3 45756.3 46068.0 46111.0 9223.1 9307.5 9321.6 9424.5 9525.3 16961.0 17372.1 17424.4 17659.8 17691.9 11110.2 12323.9 13900.7 14148.6 14529.3 11645.6 13392.5 13402.0 14296.8 14361.5 14272.4 14519.8 14671.0 14825.5 14847.3 8791.8 8860.6 8896.8 9272.3 9331.6 10513.7 14374.3 14428.1 14579.7 14689.3 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,511 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5424.73 Median:6523.80 stddev:2865.26 (43.9%) stddev-sans-first:2856.35 18:48:57 INFO - 2015-11-23 18:48:57,512 DEBUG : BROWSER_OUTPUT: Values: 1278.9 1666.3 1720.8 1724.5 1764.0 6885.4 7377.9 7635.2 7643.5 7644.2 7614.7 7721.8 7743.8 7753.7 7753.9 1947.5 1953.2 1969.3 2162.6 2187.1 2212.2 2215.5 2232.0 2233.4 2238.1 2141.8 2141.9 2262.4 2268.6 2272.3 1936.9 1971.2 1973.1 2145.1 2200.5 2177.9 2180.8 2185.6 2185.7 2186.0 7585.5 7718.8 7727.5 7731.7 7739.2 4582.6 4701.2 4705.2 4709.4 4711.2 2937.1 2978.4 3046.3 3055.8 3057.3 927.2 931.8 954.6 955.4 959.9 10683.4 10713.6 10727.9 10728.1 10745.9 4236.1 4266.5 4303.3 4320.5 4364.9 9210.1 9213.6 9228.4 9228.7 9229.7 4794.7 4820.5 4843.7 4847.2 4849.8 4387.8 4388.9 4394.4 4400.7 4400.9 3687.8 3718.4 3733.7 3742.0 3748.5 1165.6 1168.2 1176.7 1184.0 1187.2 992.6 992.6 1230.0 1323.5 1353.6 7299.7 7348.5 7395.8 7404.9 7408.9 466.6 469.1 473.5 478.7 486.5 5459.2 5615.0 5947.6 6064.9 6068.0 9168.1 9188.0 9440.8 9456.9 9486.1 9229.4 9250.2 9354.3 9364.0 9392.6 7001.6 7004.5 7038.8 7155.3 7269.3 7157.7 7247.3 7256.5 7261.9 7263.5 8212.1 8411.4 8419.3 8421.0 8422.2 8360.6 8581.0 8591.2 8706.4 8716.6 6885.2 6913.9 6953.0 6969.2 6978.0 7530.7 7562.7 7569.4 7580.5 7593.6 6981.1 7124.3 7196.3 7219.1 7239.9 7063.3 7123.6 7171.5 7182.3 7214.4 6714.6 6814.3 6841.8 6862.6 6875.2 6306.4 6330.2 6341.4 6344.7 6353.5 5835.6 5953.4 6491.7 6555.9 6603.6 18:48:57 INFO - 2015-11-23 18:48:57,512 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,512 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5352.28 Median:6094.97 stddev:1621.43 (26.6%) stddev-sans-first:1581.67 18:48:57 INFO - 2015-11-23 18:48:57,513 DEBUG : BROWSER_OUTPUT: Values: 336.5 340.1 341.2 341.3 342.5 5992.6 6017.5 6039.2 6059.3 6070.9 5708.7 5773.2 5859.3 5902.2 5991.6 4572.5 4581.8 4596.6 4596.9 4605.1 4427.0 4596.8 4597.3 4598.3 4603.1 4446.9 4459.2 4577.6 4584.8 4605.7 4574.0 4578.0 4598.0 4599.0 4600.0 4018.8 4027.7 4028.0 4028.1 4032.1 5524.4 5625.8 5648.2 5655.0 5672.6 5355.6 5356.6 5358.2 5363.0 5372.8 6361.7 6362.6 6363.0 6373.8 6374.5 4483.7 4484.0 4484.5 4498.8 4511.0 6437.5 6603.8 6612.0 6635.6 6639.9 4467.7 4494.2 4503.8 4506.8 4511.3 4560.9 4562.3 4566.6 4567.4 4568.4 5277.1 5290.7 5293.7 5295.4 5299.0 6268.6 6412.2 6433.7 6435.2 6442.5 1585.9 1595.4 1612.2 1612.8 1613.0 1059.7 1060.4 1068.7 1078.4 1080.2 6215.8 6223.9 6225.6 6239.1 6244.3 6555.5 6561.0 6562.5 6563.9 6564.7 6011.5 6119.0 6138.2 6303.6 6306.2 3560.6 3592.1 3595.1 3601.4 3602.1 6903.2 6904.0 6907.1 6909.0 6911.1 6656.7 6814.3 6824.0 6830.3 6831.5 6884.4 6890.6 6892.8 6898.2 6900.0 6575.0 6617.0 6622.1 6623.0 6632.9 6413.2 6584.6 6593.9 6595.7 6602.0 6880.6 6884.6 6887.7 6892.8 6917.1 4540.5 4565.9 4567.1 4569.4 4578.0 6509.0 6557.1 6592.2 6597.3 6607.0 6402.8 6405.4 6408.2 6408.6 6429.5 6421.4 6449.1 6452.5 6453.9 6459.8 6190.7 6327.3 6332.8 6337.1 6341.2 6481.6 6485.2 6486.3 6490.5 6493.7 6285.5 6288.5 6295.2 6300.8 6301.7 18:48:57 INFO - 2015-11-23 18:48:57,513 DEBUG : BROWSER_OUTPUT: 18:48:57 INFO - 2015-11-23 18:48:57,513 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13987.79 Median:24377.02 stddev:16372.02 (67.2%) stddev-sans-first:16387.53 18:48:57 INFO - 2015-11-23 18:48:57,513 DEBUG : BROWSER_OUTPUT: Values: 697.1 712.9 715.7 750.2 897.0 13568.1 13883.9 13899.2 13923.7 13950.3 12929.4 14509.1 14624.6 14685.6 14700.7 684.7 688.4 801.3 813.4 814.2 778.9 809.7 811.8 815.8 817.6 859.4 861.6 862.0 862.1 862.3 710.8 714.1 853.7 854.0 859.7 626.6 626.8 627.8 629.8 631.5 14134.4 14303.5 14313.4 14318.7 14339.6 12643.1 13018.6 13037.6 13411.9 13427.5 9767.4 9957.6 10220.4 10257.9 10425.1 3663.6 3707.9 3750.5 3779.3 3829.4 48256.3 49849.8 49854.5 49896.3 49956.0 9814.4 9824.7 9981.2 10088.9 10539.8 19057.4 19197.2 19199.7 19224.0 19257.5 7334.1 7374.8 7449.1 7467.2 7470.2 4299.2 4393.1 4415.6 4421.6 4473.5 3110.8 3112.3 3133.6 3141.3 3164.8 526.2 535.6 543.3 555.2 556.0 507.3 594.9 610.9 617.4 618.2 8051.2 8068.7 8212.4 8454.9 8634.0 497.8 538.2 549.8 550.2 670.9 3643.5 3679.2 3757.6 3787.7 3886.8 57974.4 58225.0 58230.1 58290.0 58482.4 52297.2 53741.2 54060.0 54843.0 54857.5 46536.0 46892.3 47256.8 47383.3 47394.0 7997.7 8064.3 8251.6 8319.8 8402.1 35323.8 35436.8 35811.4 35917.1 36332.0 42129.8 42184.8 45402.3 46518.8 46592.3 10573.3 10763.0 10766.8 10767.7 10880.1 11347.1 11635.8 12063.3 12076.2 12095.8 10894.5 11471.6 11529.5 11705.9 11741.5 10570.4 10614.7 10619.9 10625.5 10755.0 10663.8 11021.0 11059.7 11241.9 11270.7 9382.4 9449.4 9475.1 9575.6 9620.3 8570.8 8800.3 8994.6 9184.4 9407.3 18:48:57 INFO - 2015-11-23 18:48:57,513 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:48:57 INFO - 2015-11-23 18:48:57,513 DEBUG : BROWSER_OUTPUT: 18:48:58 INFO - 2015-11-23 18:48:58,227 INFO : Browser exited with error code: 0 18:48:58 INFO - 2015-11-23 18:48:58,246 INFO : Completed test dromaeo_css (00:18:32) 18:48:58 INFO - 2015-11-23 18:48:58,246 INFO : Starting test kraken 18:48:58 INFO - 2015-11-23 18:48:58,246 DEBUG : operating with platform_type : linux_ 18:48:58 INFO - 2015-11-23 18:48:58,246 INFO : Initialising browser for kraken test... 18:48:58 INFO - 2015-11-23 18:48:58,252 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpke4Gb7/profile http://localhost:48534/getInfo.html 18:49:01 INFO - 2015-11-23 18:49:01,854 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:49:01 INFO - 2015-11-23 18:49:01,854 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:49:01 INFO - 2015-11-23 18:49:01,854 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:49:01 INFO - 2015-11-23 18:49:01,854 DEBUG : BROWSER_OUTPUT: __metrics 18:49:01 INFO - 2015-11-23 18:49:01,982 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:03 INFO - 2015-11-23 18:49:03,123 INFO : Browser initialized. 18:49:03 INFO - 2015-11-23 18:49:03,123 INFO : Running cycle 1/1 for kraken test... 18:49:03 INFO - 2015-11-23 18:49:03,124 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpke4Gb7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:49:14 INFO - 2015-11-23 18:49:14,962 DEBUG : BROWSER_OUTPUT: RSS: Main: 140582912 18:49:14 INFO - 2015-11-23 18:49:14,963 DEBUG : BROWSER_OUTPUT: 18:49:17 INFO - 2015-11-23 18:49:17,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48534/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:17 INFO - 2015-11-23 18:49:17,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 178786304 18:49:17 INFO - 2015-11-23 18:49:17,986 DEBUG : BROWSER_OUTPUT: 18:49:20 INFO - 2015-11-23 18:49:20,574 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48534/tests/kraken/driver.html?testName=audio-dft) 18:49:21 INFO - 2015-11-23 18:49:21,143 DEBUG : BROWSER_OUTPUT: RSS: Main: 167890944 18:49:21 INFO - 2015-11-23 18:49:21,143 DEBUG : BROWSER_OUTPUT: 18:49:23 INFO - 2015-11-23 18:49:23,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48534/tests/kraken/driver.html?testName=audio-fft) 18:49:24 INFO - 2015-11-23 18:49:24,552 DEBUG : BROWSER_OUTPUT: RSS: Main: 150749184 18:49:24 INFO - 2015-11-23 18:49:24,553 DEBUG : BROWSER_OUTPUT: 18:49:26 INFO - 2015-11-23 18:49:26,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48534/tests/kraken/driver.html?testName=audio-oscillator) 18:49:27 INFO - 2015-11-23 18:49:27,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 154361856 18:49:27 INFO - 2015-11-23 18:49:27,134 DEBUG : BROWSER_OUTPUT: 18:49:29 INFO - 2015-11-23 18:49:29,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48534/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:29 INFO - 2015-11-23 18:49:29,893 DEBUG : BROWSER_OUTPUT: RSS: Main: 155504640 18:49:29 INFO - 2015-11-23 18:49:29,893 DEBUG : BROWSER_OUTPUT: 18:49:32 INFO - 2015-11-23 18:49:32,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48534/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:33 INFO - 2015-11-23 18:49:33,349 DEBUG : BROWSER_OUTPUT: RSS: Main: 156614656 18:49:33 INFO - 2015-11-23 18:49:33,350 DEBUG : BROWSER_OUTPUT: 18:49:36 INFO - 2015-11-23 18:49:36,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48534/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:37 INFO - 2015-11-23 18:49:37,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 157323264 18:49:37 INFO - 2015-11-23 18:49:37,285 DEBUG : BROWSER_OUTPUT: 18:49:40 INFO - 2015-11-23 18:49:40,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48534/tests/kraken/driver.html?testName=json-parse-financial) 18:49:40 INFO - 2015-11-23 18:49:40,601 DEBUG : BROWSER_OUTPUT: RSS: Main: 156467200 18:49:40 INFO - 2015-11-23 18:49:40,601 DEBUG : BROWSER_OUTPUT: 18:49:42 INFO - 2015-11-23 18:49:42,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48534/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:49:42 INFO - 2015-11-23 18:49:42,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 168546304 18:49:42 INFO - 2015-11-23 18:49:42,723 DEBUG : BROWSER_OUTPUT: 18:49:44 INFO - 2015-11-23 18:49:44,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:49:45 INFO - 2015-11-23 18:49:45,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 167411712 18:49:45 INFO - 2015-11-23 18:49:45,123 DEBUG : BROWSER_OUTPUT: 18:49:47 INFO - 2015-11-23 18:49:47,214 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:49:47 INFO - 2015-11-23 18:49:47,781 DEBUG : BROWSER_OUTPUT: RSS: Main: 175480832 18:49:47 INFO - 2015-11-23 18:49:47,781 DEBUG : BROWSER_OUTPUT: 18:49:50 INFO - 2015-11-23 18:49:50,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:49:50 INFO - 2015-11-23 18:49:50,761 DEBUG : BROWSER_OUTPUT: RSS: Main: 181551104 18:49:50 INFO - 2015-11-23 18:49:50,761 DEBUG : BROWSER_OUTPUT: 18:49:53 INFO - 2015-11-23 18:49:53,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:49:53 INFO - 2015-11-23 18:49:53,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 181497856 18:49:53 INFO - 2015-11-23 18:49:53,880 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48534/tests/kraken/driver.html?testName=ai-astar) 18:49:55 INFO - 2015-11-23 18:49:55,770 DEBUG : BROWSER_OUTPUT: RSS: Main: 239480832 18:49:55 INFO - 2015-11-23 18:49:55,770 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,770 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:49:55 INFO - 2015-11-23 18:49:55,771 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:49:55 INFO - 2015-11-23 18:49:55,771 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:49:55 INFO - 2015-11-23 18:49:55,771 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:49:55 INFO - 2015-11-23 18:49:55,771 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;106;110;112;110;98;111;111;102 18:49:55 INFO - 2015-11-23 18:49:55,771 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;123;153;166;125;149;162;126;178;124 18:49:55 INFO - 2015-11-23 18:49:55,772 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;162;161;162;161;160;161;160;161 18:49:55 INFO - 2015-11-23 18:49:55,772 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;85;94;71;71;75;124;93;71 18:49:55 INFO - 2015-11-23 18:49:55,772 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;137;92;93;94;138;93;94;138 18:49:55 INFO - 2015-11-23 18:49:55,772 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;109;110;109;109;110;110;110 18:49:55 INFO - 2015-11-23 18:49:55,772 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;146;145;145;145;146;146;147;146 18:49:55 INFO - 2015-11-23 18:49:55,772 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;95;96;95;95;95 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;58;57;58;57;57;57;57;58;56 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;67;69;69;68;68;68;68;68;71 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;89;83;85;85;86;85;92;84;84;84 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;116;121;114;115;116;130;124;113;128 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;151;150;150;148;137;148;150;155;156 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;68;66;67;65;68;66;66;68 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:49:55 INFO - 2015-11-23 18:49:55,773 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:49:55 INFO - 2015-11-23 18:49:55,774 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,893 18:49:55 INFO - 2015-11-23 18:49:55,774 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:49:55 INFO - 2015-11-23 18:49:55,774 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333395769__endTimestamp 18:49:55 INFO - 2015-11-23 18:49:55,774 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,777 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:49:55 INFO - 2015-11-23 18:49:55,778 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:49:55 INFO - 2015-11-23 18:49:55,778 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,778 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:110.50 stddev:4.56 (4.1%) stddev-sans-first:4.82 18:49:55 INFO - 2015-11-23 18:49:55,778 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 106.0 110.0 112.0 110.0 98.0 111.0 111.0 102.0 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.30 Median:151.00 stddev:19.97 (13.2%) stddev-sans-first:21.17 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: Values: 147.0 123.0 153.0 166.0 125.0 149.0 162.0 126.0 178.0 124.0 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 162.0 161.0 162.0 161.0 160.0 161.0 160.0 161.0 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,779 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.60 Median:73.00 stddev:17.28 (23.7%) stddev-sans-first:17.81 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 85.0 94.0 71.0 71.0 75.0 124.0 93.0 71.0 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:93.00 stddev:21.52 (23.1%) stddev-sans-first:22.26 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 137.0 92.0 93.0 94.0 138.0 93.0 94.0 138.0 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 109.0 110.0 109.0 109.0 110.0 110.0 110.0 18:49:55 INFO - 2015-11-23 18:49:55,780 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 146.0 145.0 145.0 145.0 146.0 146.0 147.0 146.0 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: Values: 57.0 58.0 57.0 58.0 57.0 57.0 57.0 57.0 58.0 56.0 18:49:55 INFO - 2015-11-23 18:49:55,781 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 69.0 69.0 68.0 68.0 68.0 68.0 68.0 71.0 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:2.75 (3.2%) stddev-sans-first:2.65 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: Values: 89.0 83.0 85.0 85.0 86.0 85.0 92.0 84.0 84.0 84.0 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:119.50 stddev:6.00 (5.0%) stddev-sans-first:6.34 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: Values: 118.0 116.0 121.0 114.0 115.0 116.0 130.0 124.0 113.0 128.0 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,782 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.30 Median:150.00 stddev:6.27 (4.2%) stddev-sans-first:5.43 18:49:55 INFO - 2015-11-23 18:49:55,783 DEBUG : BROWSER_OUTPUT: Values: 138.0 151.0 150.0 150.0 148.0 137.0 148.0 150.0 155.0 156.0 18:49:55 INFO - 2015-11-23 18:49:55,783 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,783 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:1.12 18:49:55 INFO - 2015-11-23 18:49:55,783 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 68.0 66.0 67.0 65.0 68.0 66.0 66.0 68.0 18:49:55 INFO - 2015-11-23 18:49:55,783 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:49:55 INFO - 2015-11-23 18:49:55,783 DEBUG : BROWSER_OUTPUT: 18:49:56 INFO - 2015-11-23 18:49:56,611 INFO : Browser exited with error code: 0 18:49:56 INFO - 2015-11-23 18:49:56,660 INFO : Completed test kraken (00:00:58) 18:49:56 INFO - 2015-11-23 18:49:56,660 INFO : Starting test v8_7 18:49:56 INFO - 2015-11-23 18:49:56,660 DEBUG : operating with platform_type : linux_ 18:49:56 INFO - 2015-11-23 18:49:56,660 INFO : Initialising browser for v8_7 test... 18:49:56 INFO - 2015-11-23 18:49:56,666 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpU_lbnP/profile http://localhost:48534/getInfo.html 18:50:00 INFO - 2015-11-23 18:50:00,159 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:50:00 INFO - 2015-11-23 18:50:00,159 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:50:00 INFO - 2015-11-23 18:50:00,159 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:50:00 INFO - 2015-11-23 18:50:00,159 DEBUG : BROWSER_OUTPUT: __metrics 18:50:00 INFO - 2015-11-23 18:50:00,255 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:50:01 INFO - 2015-11-23 18:50:01,386 INFO : Browser initialized. 18:50:01 INFO - 2015-11-23 18:50:01,387 INFO : Running cycle 1/1 for v8_7 test... 18:50:01 INFO - 2015-11-23 18:50:01,387 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpU_lbnP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:50:13 INFO - 2015-11-23 18:50:13,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 140423168 18:50:13 INFO - 2015-11-23 18:50:13,138 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,574 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48534/tests/v8_7/run.html (next: http://localhost:48534/tests/v8_7/run.html) 18:50:34 INFO - 2015-11-23 18:50:34,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 628056064 18:50:34 INFO - 2015-11-23 18:50:34,832 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,832 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:50:34 INFO - 2015-11-23 18:50:34,833 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:50:34 INFO - 2015-11-23 18:50:34,833 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:50:34 INFO - 2015-11-23 18:50:34,833 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:50:34 INFO - 2015-11-23 18:50:34,833 DEBUG : BROWSER_OUTPUT: |0;Richards;186.39328984156572 18:50:34 INFO - 2015-11-23 18:50:34,834 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.67924528301887 18:50:34 INFO - 2015-11-23 18:50:34,834 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.135788585887 18:50:34 INFO - 2015-11-23 18:50:34,834 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4990.049751243781 18:50:34 INFO - 2015-11-23 18:50:34,834 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1118.5682326621925 18:50:34 INFO - 2015-11-23 18:50:34,834 DEBUG : BROWSER_OUTPUT: |5;Earley;778.2101167315175 18:50:34 INFO - 2015-11-23 18:50:34,835 DEBUG : BROWSER_OUTPUT: |6;Boyer;12292.682926829268 18:50:34 INFO - 2015-11-23 18:50:34,835 DEBUG : BROWSER_OUTPUT: |7;RegExp;25692.30769230769 18:50:34 INFO - 2015-11-23 18:50:34,835 DEBUG : BROWSER_OUTPUT: |8;Splay;665.1785714285714 18:50:34 INFO - 2015-11-23 18:50:34,835 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 18:50:34 INFO - 2015-11-23 18:50:34,836 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:50:34 INFO - 2015-11-23 18:50:34,836 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333434825__endTimestamp 18:50:34 INFO - 2015-11-23 18:50:34,836 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,836 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:50:34 INFO - 2015-11-23 18:50:34,836 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:50:34 INFO - 2015-11-23 18:50:34,837 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,837 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:186.39 Median:186.39 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,837 DEBUG : BROWSER_OUTPUT: Values: 186.4 18:50:34 INFO - 2015-11-23 18:50:34,837 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,838 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.68 Median:188.68 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,838 DEBUG : BROWSER_OUTPUT: Values: 188.7 18:50:34 INFO - 2015-11-23 18:50:34,838 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,838 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.14 Median:281.14 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: Values: 281.1 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4990.05 Median:4990.05 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: Values: 4990.0 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1118.57 Median:1118.57 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: Values: 1118.6 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,839 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:778.21 Median:778.21 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: Values: 778.2 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12292.68 Median:12292.68 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: Values: 12292.7 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25692.31 Median:25692.31 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: Values: 25692.3 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,840 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:665.18 Median:665.18 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,841 DEBUG : BROWSER_OUTPUT: Values: 665.2 18:50:34 INFO - 2015-11-23 18:50:34,841 DEBUG : BROWSER_OUTPUT: 18:50:34 INFO - 2015-11-23 18:50:34,841 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 18:50:34 INFO - 2015-11-23 18:50:34,841 DEBUG : BROWSER_OUTPUT: Values: 4830.9 18:50:34 INFO - 2015-11-23 18:50:34,841 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:50:34 INFO - 2015-11-23 18:50:34,841 DEBUG : BROWSER_OUTPUT: 18:50:35 INFO - 2015-11-23 18:50:35,675 INFO : Browser exited with error code: 0 18:50:35 INFO - 2015-11-23 18:50:35,694 INFO : Completed test v8_7 (00:00:39) 18:50:35 INFO - 2015-11-23 18:50:35,796 INFO : Completed test suite (00:20:10) 18:50:35 INFO - 2015-11-23 18:50:35,796 DEBUG : Working with test: dromaeo_css 18:50:35 INFO - 2015-11-23 18:50:35,796 DEBUG : Generating results file: dromaeo_css 18:50:35 INFO - 2015-11-23 18:50:35,797 DEBUG : Working with test: kraken 18:50:35 INFO - 2015-11-23 18:50:35,797 DEBUG : Generating results file: kraken 18:50:35 INFO - 2015-11-23 18:50:35,798 INFO : javascript benchmark 18:50:35 INFO - 2015-11-23 18:50:35,798 DEBUG : Working with test: v8_7 18:50:35 INFO - 2015-11-23 18:50:35,798 DEBUG : Generating results file: v8_7 18:50:35 INFO - 2015-11-23 18:50:35,798 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:35 INFO - 2015-11-23 18:50:35,942 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 18:50:35 INFO - 2015-11-23 18:50:35,943 DEBUG : process_Request line: dromaeo_css 6996.91 graph.html#tests=[[72,64,35]] 18:50:35 INFO - 2015-11-23 18:50:35,943 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:36 INFO - 2015-11-23 18:50:36,055 DEBUG : process_Request line: kraken 1499.80 graph.html#tests=[[232,64,35]] 18:50:36 INFO - 2015-11-23 18:50:36,055 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:36 INFO - 2015-11-23 18:50:36,098 DEBUG : process_Request line: v8_7 20678.41 graph.html#tests=[[230,64,35]] 18:50:36 INFO - 2015-11-23 18:50:36,100 INFO : javascript benchmark 18:50:36 INFO - 2015-11-23 18:50:36,103 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3186.697263759943, 3234.191452136966, 3296.802191868488, 3301.2077101495634, 3319.087251005974, 5235.790568377261, 5318.813841515548, 5366.302380690509, 5376.274202982601, 5471.6443431176995, 5320.308359913215, 5371.274877891484, 5389.8113566025195, 5394.91907621386, 5477.4796394342575, 4895.755212239392, 4908.656394052417, 4924.482929292427, 4960.156773348539, 5013.949860501385, 4770.928436073462, 4985.052839960414, 4992.226204938241, 5022.82420115296, 5045.041442126003, 4873.098476781789, 4963.528464795839, 5023.34696489454, 5043.243513472972, 5044.47033061527, 4909.263610458424, 4922.433920099199, 4925.21196608541, 4959.8016079356785, 4972.0801651694555, 4867.561919427233, 4923.975380123113, 4944.826931057396, 4996.550310472038, 5009.499050094998, 5156.278121063016, 5220.869478263074, 5233.0, 5247.081760691902, 5268.947310526884, 5935.020721580934, 5951.137085122677, 5951.464368206839, 5952.613080149819, 5953.017752070903, 5734.598578099534, 5797.246357973479, 5856.238688970449, 5975.312839023524, 5983.311919129313, 5594.440555944414, 5637.295338082739, 5643.068893632546, 5645.943540564542, 5652.463016013513, 6075.939240607538, 6076.513878889678, 6092.3603021682975, 6100.0, 6103.489197737777, 5639.43605639441, 5723.971380143073, 5730.140478928194, 5740.9953258180485, 5910.113482977502, 5993.0, 5996.1005849122985, 6003.0, 6009.038553831366, 6041.0, 5920.2007728956905, 5924.644521328734, 5965.701714914237, 5970.701464926737, 5973.283206015306, 5643.661380317194, 5675.148727690879, 5753.568482363839, 5759.827205183768, 5947.31605865331, 4517.638588912945, 4527.7736113194205, 4536.432945881765, 4561.840335588238, 4563.726176429424, 3983.5418926823786, 3986.142979259473, 3993.5008123984503, 4031.4154447604933, 4058.857939972087, 5340.359156901197, 5422.728863556807, 5467.0705979983495, 5568.248286481355, 5575.1915972184615, 5821.301443826768, 5941.257342832146, 5942.524598032147, 5944.494717948936, 5954.07711804671, 5594.272744543265, 5599.832005039855, 5603.915941260884, 5604.8038318658655, 5608.803691870847, 4959.305697202394, 5008.499150084962, 5055.191169412949, 5104.3109180260935, 5121.795128194905, 6065.5754097212775, 6066.908996364969, 6075.0, 6077.969610151921, 6082.665453400105, 5827.825165245049, 5883.470487656132, 5883.705814709162, 5892.175095486549, 5896.233489646319, 5764.394738552392, 5894.263217097863, 6010.1585777991995, 6067.908981365194, 6113.969430152821, 5832.066869300891, 5842.5325973921135, 5873.353931067493, 5880.911786323294, 5890.2342695449315, 5692.914606280826, 5946.881062378641, 5948.4943779779205, 5949.851253718692, 5950.0, 5686.317641883001, 5688.886222275614, 5759.798407055733, 5819.679917604572, 5867.413258674099, 5769.91345129815, 5786.33457152416, 5800.8259752207505, 5803.738831752666, 5805.970970145122, 5539.806106786243, 5723.456271654186, 5733.111367738007, 5737.483626473638, 5738.311402631711, 5636.464535869086, 5648.689322087324, 5675.829725108253, 5788.102844059177, 5821.970890145692, 5637.774489020558, 5671.773129074791, 5691.117876729114, 5693.971530142323, 5746.13807928814], "unit": "ms", "name": "dojo.html", "value": 5412.099253057932}, {"lowerIsBetter": false, "replicates": [551.7296524702897, 599.0953659973441, 599.5533327670885, 606.0636316890403, 610.8106486989032, 31376.372472550564, 39316.21367572647, 39643.0, 39794.8010259949, 39916.40125398114, 43265.78367108168, 43296.567034329644, 43741.0, 43771.34342984858, 43815.12369752603, 7378.077740282465, 7609.353204977556, 8532.57337133144, 8751.0, 9990.850137247948, 10082.143017843493, 10189.490525473697, 10197.541110650038, 10198.439085850276, 10200.693979180636, 10139.290249682525, 10244.487775611227, 10251.641192558262, 10254.128399086087, 10266.38401695897, 7632.198619144993, 7813.76558703237, 10290.279680422373, 10491.895081049168, 10518.105960993249, 10015.69952901414, 10095.44475053879, 10170.23723220754, 10172.542235599416, 10184.388936663823, 40242.39636405456, 40282.79858600718, 40651.7967410164, 40740.1851962962, 40756.38865417021, 3184.952225716616, 3223.468127758917, 3256.218507558193, 3304.0, 3592.892213233607, 29893.551596726065, 30379.0, 30380.0, 30704.0, 31524.842375788205, 3764.0, 3866.5553461352024, 3891.455196272496, 3940.7438516496622, 4018.57804930481, 432699.0, 433819.0, 436277.0, 437812.0, 441367.9999999968, 60647.696761516796, 61169.35383077116, 61503.0, 61589.0, 61592.0, 57314.71342643344, 57762.71118644464, 63392.68303658452, 63719.36280637134, 63730.0, 15378.692426151414, 15580.532584022498, 15639.452619158275, 15642.296096675676, 15673.451429200151, 9862.408255504693, 9874.160696340748, 9885.25860560461, 9886.15967642744, 9944.0, 20049.09779059946, 20225.674047484434, 20236.898815506123, 20237.595248094956, 20263.493412664506, 7856.310893157998, 7903.407244456689, 7940.563269020259, 8056.274935255738, 8058.35533157346, 926.1201858234704, 929.5212965322864, 969.3989726369696, 985.9063388978036, 1073.7852429514128, 28703.0, 28872.42255154885, 28991.710082898902, 29268.0, 29403.264918376783, 1038.133158812385, 1052.58422922945, 1114.7658991611852, 1120.1207052463776, 1157.5948418053613, 14955.0, 15096.849031509764, 15115.848841511664, 15212.923935380251, 15319.0, 324759.0, 329193.0, 329409.0, 330052.0, 330686.0, 52508.47491525036, 53330.0, 53389.0, 53401.0, 53496.465035349145, 1333.2866916199787, 1335.8196643453205, 1343.1538130977617, 1345.9125156864773, 1355.620426280643, 27680.44639107247, 28378.0, 28424.005159820135, 28608.856955715088, 28895.711042889303, 47229.52770472251, 47614.76192619015, 47653.28520072271, 47677.0, 47735.28397073977, 306248.0, 306688.0, 307683.0, 308428.0, 309014.0, 32297.838510807294, 32535.18662033468, 32596.34807303888, 32811.671883281815, 32960.340793183525, 42305.5769442314, 42525.5747442534, 42847.0, 43293.3505997404, 43857.56142438535, 35685.46471802977, 35817.283654326246, 36301.636983629825, 37077.258454831295, 37549.81225093857, 27953.44093118167, 30082.09753707392, 32562.51156232611, 35406.822965885, 35799.0, 33284.33431331408, 34092.65907340994, 34604.48093278653, 34639.826800865834, 34641.82679086588, 29029.7097029027, 30439.0, 35919.28161436809, 37444.0, 38185.6181438182, 25250.242492725247, 25844.612330814678, 28091.578626320214, 28201.858990704914, 28412.715872841833], "unit": "ms", "name": "ext.html", "value": 19093.811641731798}, {"lowerIsBetter": false, "replicates": [355.14055984517466, 356.64691954964593, 356.9625189355116, 359.288608555061, 359.46080878681977, 14184.36170372333, 14279.857201428009, 16164.757528637065, 16606.584835379108, 17417.564560885985, 15164.84835151648, 16351.918240408811, 16354.7546786798, 16458.0, 17476.126193690296, 5215.660982036165, 5356.892862142755, 5363.249145119687, 5363.731813409314, 5366.275552800361, 3930.66589339905, 3946.151577410856, 5348.144296912495, 5410.83767486974, 5891.086881533369, 6035.336113027563, 6063.090536419529, 6101.572889897709, 6115.113308570255, 6119.66341851198, 5098.311727916721, 5231.319928409321, 5238.4499627539135, 5240.81657142002, 5999.910001349983, 4972.5276098770555, 5017.322661440695, 5025.1708468102715, 5036.320096786923, 5259.631825772198, 11596.94201528987, 11764.529418823236, 11814.527418903319, 11824.172307938448, 11916.821247681291, 1970.7142464342733, 2008.3974807557674, 2080.750309962799, 2097.3812725245934, 2119.4065661614773, 9575.760605984906, 9762.170215531618, 9953.25350598708, 11917.284962902253, 12620.990320774315, 1777.3779177287975, 1798.7122060470392, 1823.4985379020743, 1855.434092601756, 1859.1447933950265, 58393.41606583922, 61985.0, 62929.37070629281, 63480.68259658718, 63786.362136378506, 9468.195203407788, 9672.177864881423, 9881.901180988098, 9996.0, 10047.899521004696, 12988.415521301564, 12992.415341309475, 12998.870011299956, 13000.08999370035, 13018.088733788543, 7237.276272372721, 7249.383802376857, 7370.96314518424, 7485.737999170003, 7504.474686771982, 7326.230745771723, 7326.743563975236, 7366.373858222111, 7406.962965185247, 7413.1104267488245, 4200.474940632421, 4236.957630423718, 4283.271843786564, 4287.957120428819, 4303.483581970184, 1646.588352911772, 1666.7916510436196, 1697.5416637508051, 1702.5403141152067, 1703.4548944337694, 887.8401887660154, 896.112848280211, 970.9126178643955, 994.980145351021, 995.5022488755623, 16164.919175404048, 16748.0, 16759.58101047459, 17068.146592670317, 17185.7422138668, 913.9862902056475, 919.7516670498928, 919.8712180294764, 923.5613083785282, 989.6437282578266, 9224.123708247706, 9478.194353479894, 9618.134367906921, 9950.0, 9982.151517121127, 564670.0, 566524.0, 569822.1508892429, 577266.0, 577695.0, 78574.0, 78976.60511697405, 79982.20017799865, 82275.5886220565, 82979.17020829715, 1112.6662001399548, 1133.3426612564692, 1136.5510623303915, 1150.1144119028302, 1160.7504386556932, 15379.538613841603, 15379.538613841603, 15432.459863904709, 15538.0, 15729.292181851615, 33877.0, 34325.48511772375, 34524.0, 35065.123371915906, 35513.64486355103, 44236.0, 44645.33032004457, 45756.31365529453, 46068.0, 46111.0, 9223.123803238681, 9307.488088155216, 9321.627134914528, 9424.481653509123, 9525.333226674065, 16961.0, 17372.13139343053, 17424.390146344816, 17659.823401765818, 17691.911540442215, 11110.16673749456, 12323.876761232274, 13900.721985560434, 14148.575542733735, 14529.273536323353, 11645.59240426581, 13392.464301427834, 13402.0, 14296.785548216993, 14361.497347592784, 14272.357743901757, 14519.78220326717, 14671.0, 14825.481108161162, 14847.331870065656, 8791.824163516565, 8860.556972151495, 8896.822063558822, 9272.258219342571, 9331.58007889633, 10513.737156571147, 14374.281285935871, 14428.062175958528, 14579.708405832036, 14689.338979746151], "unit": "ms", "name": "jquery.html", "value": 8659.854761963266}, {"lowerIsBetter": false, "replicates": [1278.9424475898584, 1666.2668425892607, 1720.7669133930526, 1724.4998950304412, 1764.0474143962253, 6885.449164066875, 7377.889331660022, 7635.160132385433, 7643.541387516746, 7644.197359277279, 7614.73348432805, 7721.806954826118, 7743.7676869694, 7753.689852405912, 7753.92246077539, 1947.4936516505668, 1953.150379584885, 1969.3008981811465, 2162.5566758814443, 2187.136081247906, 2212.236778311488, 2215.501512159767, 2232.0, 2233.4416395901026, 2238.149503188786, 2141.7858214178614, 2141.9464513387134, 2262.3891549281684, 2268.5689718953427, 2272.250157448038, 1936.9128389222449, 1971.2016633263552, 1973.1416833677397, 2145.1419432227076, 2200.504886400563, 2177.934661960144, 2180.7819218078225, 2185.5628874225217, 2185.7486389065302, 2186.0162926683056, 7585.506942048749, 7718.807029824243, 7727.536347819093, 7731.690732370698, 7739.187385324516, 4582.587567118942, 4701.153792317382, 4705.247160454311, 4709.411323584552, 4711.24620060792, 2937.089502254308, 2978.37454134632, 3046.2536678513684, 3055.847207639609, 3057.250973511482, 927.1701826864986, 931.8462453695132, 954.5847556312957, 955.3742298794301, 959.9088086631759, 10683.358998460117, 10713.625023124154, 10727.946360268226, 10728.088112510446, 10745.892541074645, 4236.131593023423, 4266.466691663542, 4303.311470164821, 4320.503142138694, 4364.912701745948, 9210.125038121369, 9213.63145474187, 9228.40015398997, 9228.723138305862, 9229.723108306762, 4794.688345257547, 4820.517948205152, 4843.685160464559, 4847.224444088929, 4849.75751212445, 4387.824487020547, 4388.8902777430185, 4394.36281739146, 4400.669949753781, 4400.9437734943385, 3687.8403625233577, 3718.4236443351324, 3733.6826369758874, 3742.045778326509, 3748.493953316322, 1165.6386520178771, 1168.2172944127456, 1176.7352345722315, 1184.029096141156, 1187.2105050141558, 992.5583115513527, 992.5979978108877, 1229.9852621587227, 1323.4970711129708, 1353.607453838376, 7299.70801167958, 7348.52234604749, 7395.8151046224275, 7404.925950740531, 7408.85182296351, 466.60571816814735, 469.0852836967926, 473.5453964194343, 478.71755664158314, 486.4624589828189, 5459.153831156098, 5614.9719251403485, 5947.613405128653, 6064.878702425926, 6067.969660151671, 9168.129027742354, 9188.0, 9440.811183776423, 9456.90543094574, 9486.051394860597, 9229.446233225894, 9250.21373183287, 9354.345195836226, 9363.953180234055, 9392.62429502826, 7001.649917504104, 7004.509684322151, 7038.788836334918, 7155.3202445767565, 7269.34575888162, 7157.677904494414, 7247.347738703331, 7256.492045556972, 7261.927380726125, 7263.4915555910575, 8212.096669366485, 8411.4112012161, 8419.326453883798, 8420.957895210484, 8422.157784221528, 8360.623771930155, 8580.957095214484, 8591.226789588967, 8706.390552661253, 8716.564171791511, 6885.208201056742, 6913.89629155553, 6952.965235173994, 6969.233384327618, 6978.0, 7530.698772049276, 7562.659680314509, 7569.3566046886635, 7580.507267027626, 7593.620318984139, 6981.0924579804305, 7124.287571242834, 7196.280371962762, 7219.133703955559, 7239.8914016290855, 7063.293670633101, 7123.608201548965, 7171.533850299714, 7182.317679820409, 7214.422846172399, 6714.630695311805, 6814.318568143146, 6841.760538381132, 6862.588244705334, 6875.1749790025515, 6306.400891915261, 6330.177076979963, 6341.397567231105, 6344.651044192429, 6353.523485738496, 5835.591508594358, 5953.374895635896, 6491.740330386731, 6555.868882622416, 6603.636799976048], "unit": "ms", "name": "mootools.html", "value": 4350.632418438344}, {"lowerIsBetter": false, "replicates": [336.4902173207589, 340.0597348331862, 341.1675511751328, 341.25946695670393, 342.4982400782853, 5992.610480318782, 6017.458428741413, 6039.245094363205, 6059.303180134275, 6070.9392906070925, 5708.657480551159, 5773.249477567921, 5859.267591551056, 5902.232709747726, 5991.550633702479, 4572.5427457254345, 4581.839635612754, 4596.609288210506, 4596.862094137181, 4605.102005109005, 4427.0, 4596.816127354902, 4597.264437689971, 4598.287265473857, 4603.125406172817, 4446.933296000562, 4459.15276097542, 4577.633789296865, 4584.770761461914, 4605.654575906804, 4574.0, 4577.9771101144615, 4598.0344127733215, 4598.97700511497, 4599.954000459986, 4018.7789671568053, 4027.7381970172137, 4028.0, 4028.0735430850777, 4032.072623296658, 5524.364698059734, 5625.831225063254, 5648.152777083429, 5655.010373184677, 5672.631278966854, 5355.598330125217, 5356.571474282048, 5358.196270559409, 5362.973185134049, 5372.811951581715, 6361.650109243989, 6362.5546211765195, 6362.968185159091, 6373.808785736435, 6374.458171055466, 4483.708558943658, 4484.035932274544, 4484.461864576239, 4498.842540511099, 4510.954890451087, 6437.485001199892, 6603.7688680895935, 6612.0, 6635.601863888134, 6639.933600664028, 4467.664925130628, 4494.213512635276, 4503.797329120198, 4506.797194126272, 4511.300748384006, 4560.908781824345, 4562.270036794097, 4566.611837993742, 4567.36056952027, 4568.428946381702, 5277.076511610453, 5290.735463226823, 5293.655912365684, 5295.391030031596, 5299.0, 6268.561200715906, 6412.198475190601, 6433.678316084177, 6435.22777266731, 6442.452391546677, 1585.9207039647972, 1595.4285029246182, 1612.1536193498507, 1612.8145263294637, 1612.9516114516584, 1059.7138772531373, 1060.4061725433783, 1068.7274744940146, 1078.3907092492716, 1080.189857606795, 6215.844603884849, 6223.937760622336, 6225.564210505312, 6239.06414037898, 6244.344343843922, 6555.475561955032, 6561.048647946021, 6562.507811914125, 6563.901541476882, 6564.671766411756, 6011.48902343297, 6119.0, 6138.202033735586, 6303.558750887486, 6306.243250809932, 3560.572731272212, 3592.0660628236847, 3595.1191957970464, 3601.405768048243, 3602.081469225383, 6903.240643529208, 6903.965480172667, 6907.102076729993, 6909.0, 6911.136107986502, 6656.733730650816, 6814.318568143245, 6824.0, 6830.316968303229, 6831.487638427137, 6884.449244060564, 6890.586564806027, 6892.793216203522, 6898.206706228748, 6899.965500172467, 6574.967125164344, 6617.0, 6622.07290979273, 6623.0, 6632.867342653217, 6413.16628838248, 6584.637844918576, 6593.868122637617, 6595.736170553124, 6601.966990165019, 6880.621565813928, 6884.621345826027, 6887.7244910203035, 6892.758753443605, 6917.066196063467, 4540.545945405565, 4565.885852853572, 4567.06375193078, 4569.36028955953, 4577.954220457886, 6509.006901103739, 6557.147570815763, 6592.208934927839, 6597.340265973365, 6606.966965165144, 6402.775902843378, 6405.3594640535575, 6408.166938297992, 6408.551401401857, 6429.453496452854, 6421.422072013541, 6449.097126402401, 6452.516061295328, 6453.9354606453335, 6459.806205813833, 6190.7214175363115, 6327.303996560283, 6332.810015699536, 6337.112804207322, 6341.239051313687, 6481.611103333815, 6485.156929599122, 6486.286508484157, 6490.48076153916, 6493.740250389931, 6285.497160227263, 6288.490762217127, 6295.244570651551, 6300.842478938063, 6301.684915754102], "unit": "ms", "name": "prototype.html", "value": 4824.062399747623}, {"lowerIsBetter": false, "replicates": [697.1355519156244, 712.8716830970424, 715.749487679312, 750.2347605442448, 897.0446474504652, 13568.118072325293, 13883.861161388382, 13899.235542045184, 13923.651908702263, 13950.302484875767, 12929.418176182071, 14509.129452232874, 14624.561263162122, 14685.632859178499, 14700.705985880277, 684.7192651013086, 688.4079691465361, 801.2748462641305, 813.3655748516137, 814.1980149552686, 778.8559116563425, 809.6761295481825, 811.8335741173075, 815.8286759780424, 817.5544328341039, 859.3726579596898, 861.5778268648348, 862.0474375814729, 862.1421685422981, 862.3144600042981, 710.8222944263935, 714.1287629092499, 853.6841368693592, 853.9871901921476, 859.6819176904523, 626.580191271849, 626.7931582577738, 627.7645882793952, 629.7732816186169, 631.4758750237293, 14134.36395362211, 14303.499377521737, 14313.355898984571, 14318.71362572753, 14339.641508962255, 12643.114981951267, 13018.609441716764, 13037.608871733863, 13411.932940335333, 13427.462901484027, 9767.355890037297, 9957.601695932153, 10220.437875916821, 10257.89742102577, 10425.113865321457, 3663.6336366363416, 3707.888763337104, 3750.5311836020496, 3779.281936432069, 3829.425586162098, 48256.27615585769, 49849.750751246014, 49854.50145498535, 49896.25155622632, 49956.0, 9814.362066465657, 9824.65613703517, 9981.201503879673, 10088.949555252177, 10539.789204216026, 19057.428277151706, 19197.232110715417, 19199.712004319947, 19224.0, 19257.51856203606, 7334.083239595051, 7374.778756637309, 7449.143348514882, 7467.178610352857, 7470.178280389153, 4299.161663475592, 4393.0774537347515, 4415.580519850609, 4421.602055814992, 4473.507914129443, 3110.8133511989354, 3112.3464072544566, 3133.5613014178034, 3141.340318533088, 3164.7784655074383, 526.2369564132024, 535.5528134008147, 543.2964311216983, 555.2393221286782, 555.9749811258503, 507.26446652353496, 594.9113122984927, 610.9003793172272, 617.3980369140143, 618.1994317359031, 8051.194880511902, 8068.7175948841505, 8212.384071194565, 8454.915450845536, 8633.956830215935, 497.76604995651985, 538.1550964984937, 549.8268045565634, 550.1829782772579, 670.9127813384229, 3643.508126402902, 3679.19057807282, 3757.5866654667966, 3787.6780473659496, 3886.8056597170594, 57974.420255796904, 58225.0, 58230.12654810181, 58290.0, 58482.415175848546, 52297.215541766906, 53741.1938820918, 54060.0, 54843.0, 54857.45142548603, 46536.0, 46892.2966155508, 47256.763716181886, 47383.28925066127, 47394.0, 7997.720079797179, 8064.314533264738, 8251.628676709443, 8319.750407487785, 8402.117777633504, 35323.82338088293, 35436.82281588575, 35811.3884875176, 35917.1020724484, 36332.0, 42129.78935105305, 42184.789076054425, 45402.31896521489, 46518.767406162755, 46592.30111548397, 10573.312734672221, 10763.0, 10766.838497422388, 10767.676969690921, 10880.075193608633, 11347.092232621535, 11635.825462617899, 12063.336516491678, 12076.21504602198, 12095.81856272139, 10894.509747061244, 11471.598494053002, 11529.538818447169, 11705.941470292593, 11741.471633776671, 10570.41862697559, 10614.73463163427, 10619.893801061891, 10625.468726563795, 10755.0, 10663.786724265627, 11021.0, 11059.723506912069, 11241.887581124083, 11270.718232044264, 9382.390144640576, 9449.385789923634, 9475.147236748726, 9575.569099390683, 9620.278479113955, 8570.785730356542, 8800.251978581893, 8994.55027248648, 9184.357095003286, 9407.341486095907], "unit": "ms", "name": "yui.html", "value": 6246.883957639797}], "name": "dromaeo_css", "value": 6996.910270640314}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 106.0, 110.0, 112.0, 110.0, 98.0, 111.0, 111.0, 102.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [147.0, 123.0, 153.0, 166.0, 125.0, 149.0, 162.0, 126.0, 178.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.3}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 162.0, 161.0, 162.0, 161.0, 160.0, 161.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 85.0, 94.0, 71.0, 71.0, 75.0, 124.0, 93.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 82.6}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 137.0, 92.0, 93.0, 94.0, 138.0, 93.0, 94.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0, 58.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 69.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [89.0, 83.0, 85.0, 85.0, 86.0, 85.0, 92.0, 84.0, 84.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.7}, {"lowerIsBetter": true, "replicates": [118.0, 116.0, 121.0, 114.0, 115.0, 116.0, 130.0, 124.0, 113.0, 128.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.5}, {"lowerIsBetter": true, "replicates": [138.0, 151.0, 150.0, 150.0, 148.0, 137.0, 148.0, 150.0, 155.0, 156.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.3}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 68.0, 66.0, 67.0, 65.0, 68.0, 66.0, 66.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.6}], "name": "kraken", "value": 1499.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [186.39328984156572], "unit": "score", "name": "Richards", "value": 189.39522999999994}, {"lowerIsBetter": false, "replicates": [188.67924528301887], "unit": "score", "name": "DeltaBlue", "value": 350.4253999999999}, {"lowerIsBetter": false, "replicates": [281.135788585887], "unit": "score", "name": "Encrypt", "value": 946.8058169999996}, {"lowerIsBetter": false, "replicates": [4990.049751243781], "unit": "score", "name": "Decrypt", "value": 53.34235393818545}, {"lowerIsBetter": false, "replicates": [1118.5682326621925], "unit": "score", "name": "RayTrace", "value": 661.550166}, {"lowerIsBetter": false, "replicates": [778.2101167315175], "unit": "score", "name": "Earley", "value": 856.4049549999997}, {"lowerIsBetter": false, "replicates": [12292.682926829268], "unit": "score", "name": "Boyer", "value": 54.21623611111113}, {"lowerIsBetter": false, "replicates": [25692.30769230769], "unit": "score", "name": "RegExp", "value": 35.4575}, {"lowerIsBetter": false, "replicates": [665.1785714285714], "unit": "score", "name": "Splay", "value": 122.50995973154365}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20678.410273067504}]} 18:50:36 INFO - RETURN: dromaeo_css: 6996.91 18:50:36 INFO - RETURN: kraken: 1499.80 18:50:36 INFO - RETURN: v8_7: 20678.41 18:50:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20678.41"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1499.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6996.91"}}} 18:50:36 INFO - Return code: 0 18:50:36 INFO - # TBPL SUCCESS # 18:50:36 INFO - Running post-action listener: _resource_record_post_action 18:50:36 INFO - Running post-run listener: _resource_record_post_run 18:50:37 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 4096; Write bytes: 512303104; Read time: 12; Write time: 489500 18:50:37 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 230871040; Read time: 0; Write time: 335764 18:50:37 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 4096; Write bytes: 276598784; Read time: 12; Write time: 153292 18:50:37 INFO - Running post-run listener: _upload_blobber_files 18:50:37 INFO - Blob upload gear active. 18:50:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:50:37 INFO - Copying logs to upload dir... 18:50:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.120360 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-11-23 18:50:36.351709) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:36.356641) ========= 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010071 build_url: 'https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:36.408528) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:36.408905) ========= 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=5400c60ebd4f8d7cf28c0934000001b8-1448330780.664618-1577196513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004544 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:36.459526) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:50:36.459887) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:50:36.460254) ========= ========= Total master_lag: 0.24 =========