builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-029 starttime: 1448094815.79 results: success (0) buildid: 20151120223009 builduid: cb5dfffebce841b8a23a9166c06eae6c revision: de4585e9617cb5b888ebec7296080bc4c33acaa5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.790376) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.790749) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.791033) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.007356 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.812552) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.812823) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.833736) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.834086) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004651 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.882537) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.882803) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.883165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:35.883436) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-21 00:33:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2015-11-21 00:33:36 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.185896 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.083145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.083415) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015254 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.134685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.135063) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev de4585e9617cb5b888ebec7296080bc4c33acaa5 --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/mozilla-inbound --rev de4585e9617cb5b888ebec7296080bc4c33acaa5 --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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-21 00:33:36,411 truncating revision to first 12 chars 2015-11-21 00:33:36,411 Setting DEBUG logging. 2015-11-21 00:33:36,411 attempt 1/10 2015-11-21 00:33:36,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/de4585e9617c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-21 00:33:36,651 unpacking tar archive at: mozilla-inbound-de4585e9617c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.500629 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.648303) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.648579) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.660495) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.660756) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-21 00:33:36.661146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-11-21 00:33:36.661665) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:33:37 INFO - MultiFileLogger online at 20151121 00:33:37 in /builds/slave/test-pgo 00:33:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 00:33:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 00:33:37 INFO - {'append_to_log': False, 00:33:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 00:33:37 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 00:33:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 00:33:37 INFO - 'branch': 'Mozilla-Inbound', 00:33:37 INFO - 'buildbot_json_path': 'buildprops.json', 00:33:37 INFO - 'config_files': ('talos/linux_config.py',), 00:33:37 INFO - 'default_actions': ('clobber', 00:33:37 INFO - 'read-buildbot-config', 00:33:37 INFO - 'download-and-extract', 00:33:37 INFO - 'populate-webroot', 00:33:37 INFO - 'create-virtualenv', 00:33:37 INFO - 'install', 00:33:37 INFO - 'run-tests'), 00:33:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:33:37 INFO - 'download_minidump_stackwalk': True, 00:33:37 INFO - 'download_symbols': 'ondemand', 00:33:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:33:37 INFO - 'tooltool.py': '/tools/tooltool.py', 00:33:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:33:37 INFO - '/tools/misc-python/virtualenv.py')}, 00:33:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:33:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:33:37 INFO - 'installer_path': 'installer.exe', 00:33:37 INFO - 'log_level': 'info', 00:33:37 INFO - 'log_name': 'talos', 00:33:37 INFO - 'log_to_console': True, 00:33:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:33:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:33:37 INFO - 'opt_config_files': (), 00:33:37 INFO - 'pip_index': False, 00:33:37 INFO - 'sps_profile': False, 00:33:37 INFO - 'sps_profile_interval': 0, 00:33:37 INFO - 'suite': 'dromaeojs-e10s', 00:33:37 INFO - 'system_bits': '64', 00:33:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:33:37 INFO - 'title': 'talos-linux64-ix-029', 00:33:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:33:37 INFO - 'use_talos_json': True, 00:33:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 00:33:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:33:37 INFO - 'work_dir': 'build'} 00:33:37 INFO - ##### 00:33:37 INFO - ##### Running clobber step. 00:33:37 INFO - ##### 00:33:37 INFO - Running pre-action listener: _resource_record_pre_action 00:33:37 INFO - Running main action method: clobber 00:33:37 INFO - rmtree: /builds/slave/test-pgo/build 00:33:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 00:33:37 INFO - Running post-action listener: _resource_record_post_action 00:33:37 INFO - ##### 00:33:37 INFO - ##### Running read-buildbot-config step. 00:33:37 INFO - ##### 00:33:37 INFO - Running pre-action listener: _resource_record_pre_action 00:33:37 INFO - Running main action method: read_buildbot_config 00:33:37 INFO - Using buildbot properties: 00:33:37 INFO - { 00:33:37 INFO - "properties": { 00:33:37 INFO - "buildnumber": 455, 00:33:37 INFO - "product": "firefox", 00:33:37 INFO - "script_repo_revision": "production", 00:33:37 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 00:33:37 INFO - "repository": "", 00:33:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 00:33:37 INFO - "buildid": "20151120223009", 00:33:37 INFO - "slavename": "talos-linux64-ix-029", 00:33:37 INFO - "pgo_build": "True", 00:33:37 INFO - "basedir": "/builds/slave/test-pgo", 00:33:37 INFO - "project": "", 00:33:37 INFO - "platform": "ubuntu64_hw", 00:33:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 00:33:37 INFO - "slavebuilddir": "test", 00:33:37 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 00:33:37 INFO - "branch": "mozilla-inbound", 00:33:37 INFO - "repo_path": "integration/mozilla-inbound", 00:33:37 INFO - "stage_platform": "linux64-pgo", 00:33:37 INFO - "builduid": "cb5dfffebce841b8a23a9166c06eae6c", 00:33:37 INFO - "revision": "de4585e9617cb5b888ebec7296080bc4c33acaa5" 00:33:37 INFO - }, 00:33:37 INFO - "sourcestamp": { 00:33:37 INFO - "repository": "", 00:33:37 INFO - "hasPatch": false, 00:33:37 INFO - "project": "", 00:33:37 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 00:33:37 INFO - "changes": [ 00:33:37 INFO - { 00:33:37 INFO - "category": null, 00:33:37 INFO - "files": [ 00:33:37 INFO - { 00:33:37 INFO - "url": null, 00:33:37 INFO - "name": "https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 00:33:37 INFO - } 00:33:37 INFO - ], 00:33:37 INFO - "repository": "", 00:33:37 INFO - "rev": "de4585e9617cb5b888ebec7296080bc4c33acaa5", 00:33:37 INFO - "who": "sendchange", 00:33:37 INFO - "when": 1448094768, 00:33:37 INFO - "number": 6692122, 00:33:37 INFO - "comments": "", 00:33:37 INFO - "project": "", 00:33:37 INFO - "at": "Sat 21 Nov 2015 00:32:48", 00:33:37 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 00:33:37 INFO - "revlink": "", 00:33:37 INFO - "properties": [ 00:33:37 INFO - [ 00:33:37 INFO - "buildid", 00:33:37 INFO - "20151120223009", 00:33:37 INFO - "Change" 00:33:37 INFO - ], 00:33:37 INFO - [ 00:33:37 INFO - "builduid", 00:33:37 INFO - "cb5dfffebce841b8a23a9166c06eae6c", 00:33:37 INFO - "Change" 00:33:37 INFO - ], 00:33:37 INFO - [ 00:33:37 INFO - "pgo_build", 00:33:37 INFO - "True", 00:33:37 INFO - "Change" 00:33:37 INFO - ] 00:33:37 INFO - ], 00:33:37 INFO - "revision": "de4585e9617cb5b888ebec7296080bc4c33acaa5" 00:33:37 INFO - } 00:33:37 INFO - ], 00:33:37 INFO - "revision": "de4585e9617cb5b888ebec7296080bc4c33acaa5" 00:33:37 INFO - } 00:33:37 INFO - } 00:33:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 00:33:37 INFO - Running post-action listener: _resource_record_post_action 00:33:37 INFO - ##### 00:33:37 INFO - ##### Running download-and-extract step. 00:33:37 INFO - ##### 00:33:37 INFO - Running pre-action listener: _resource_record_pre_action 00:33:37 INFO - Running main action method: download_and_extract 00:33:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 00:33:37 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')]} 00:33:37 INFO - https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 00:33:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/test_packages.json 00:33:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/test_packages.json 00:33:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 00:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 00:33:47 INFO - Downloaded 1302 bytes. 00:33:47 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 00:33:47 INFO - Using the following test package requirements: 00:33:47 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 00:33:47 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:33:47 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'jsshell-linux-x86_64.zip'], 00:33:47 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:33:47 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 00:33:47 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:33:47 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:33:47 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:33:47 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 00:33:47 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 00:33:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:33:47 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:33: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')]} 00:33:47 INFO - https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:33:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 00:33:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 00:33:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:53 INFO - Downloaded 22525891 bytes. 00:33:53 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 00:33:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 00:33:53 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 00:33:54 INFO - Return code: 0 00:33:54 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 00:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:33:54 INFO - https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:33:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 00:33:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 00:33:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:55 INFO - Downloaded 22525891 bytes. 00:33:55 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 00:33:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 00:33:55 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 00:33:56 INFO - Return code: 0 00:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:33:56 INFO - https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 00:33:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 00:33:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 00:33:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:57 INFO - Downloaded 11130997 bytes. 00:33:58 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 00:33:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 00:33:58 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 00:33:58 INFO - Return code: 0 00:33:58 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')]} 00:33:58 INFO - https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 00:33:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 00:33:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 00:33:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:33:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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 00:34:01 INFO - Downloaded 57432705 bytes. 00:34:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 00:34:01 INFO - mkdir: /builds/slave/test-pgo/properties 00:34:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 00:34:01 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 00:34:01 INFO - Contents: 00:34:01 INFO - build_url:https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 00:34:01 INFO - Running post-action listener: _resource_record_post_action 00:34:01 INFO - Running post-action listener: set_extra_try_arguments 00:34:01 INFO - ##### 00:34:01 INFO - ##### Running populate-webroot step. 00:34:01 INFO - ##### 00:34:01 INFO - Running pre-action listener: _resource_record_pre_action 00:34:01 INFO - Running main action method: populate_webroot 00:34:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:34:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:34:02 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 00:34:02 INFO - '../fennec_ids.txt'], 00:34:02 INFO - 'tests': ['tcheck2']}, 00:34:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:34:02 INFO - '--tppagecycles', 00:34:02 INFO - '7'], 00:34:02 INFO - 'tests': ['tsvgm']}}, 00:34:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 00:34:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:34:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 00:34:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 00:34:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:34:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:34:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'tests': ['damp', 'tps']}, 00:34:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['damp', 'tps']}, 00:34:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 00:34:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:34:02 INFO - 'other': {'tests': ['a11yr', 00:34:02 INFO - 'ts_paint', 00:34:02 INFO - 'tpaint', 00:34:02 INFO - 'sessionrestore', 00:34:02 INFO - 'sessionrestore_no_auto_restore']}, 00:34:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['a11yr', 00:34:02 INFO - 'ts_paint', 00:34:02 INFO - 'tpaint', 00:34:02 INFO - 'sessionrestore', 00:34:02 INFO - 'sessionrestore_no_auto_restore']}, 00:34:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['a11yr', 00:34:02 INFO - 'ts_paint', 00:34:02 INFO - 'tpaint', 00:34:02 INFO - 'sessionrestore', 00:34:02 INFO - 'sessionrestore_no_auto_restore']}, 00:34:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['a11yr', 00:34:02 INFO - 'ts_paint', 00:34:02 INFO - 'tpaint', 00:34:02 INFO - 'sessionrestore', 00:34:02 INFO - 'sessionrestore_no_auto_restore']}, 00:34:02 INFO - 'other_l64': {'tests': ['a11yr', 00:34:02 INFO - 'ts_paint', 00:34:02 INFO - 'tpaint', 00:34:02 INFO - 'sessionrestore', 00:34:02 INFO - 'sessionrestore_no_auto_restore']}, 00:34:02 INFO - 'other_nol64': {'tests': ['a11yr', 00:34:02 INFO - 'ts_paint', 00:34:02 INFO - 'tpaint', 00:34:02 INFO - 'sessionrestore', 00:34:02 INFO - 'sessionrestore_no_auto_restore']}, 00:34:02 INFO - 'svgr': {'tests': ['tsvgx', 00:34:02 INFO - 'tsvgr_opacity', 00:34:02 INFO - 'tart', 00:34:02 INFO - 'tscrollx', 00:34:02 INFO - 'cart']}, 00:34:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['tsvgx', 00:34:02 INFO - 'tsvgr_opacity', 00:34:02 INFO - 'tart', 00:34:02 INFO - 'tscrollx', 00:34:02 INFO - 'cart']}, 00:34:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'tests': ['tp5o']}, 00:34:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'talos_options': ['--e10s'], 00:34:02 INFO - 'tests': ['tp5o']}, 00:34:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'talos_options': ['--xperf_path', 00:34:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:34:02 INFO - 'tests': ['tp5n']}, 00:34:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:34:02 INFO - 'talos_options': ['--e10s', 00:34:02 INFO - '--xperf_path', 00:34:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:34:02 INFO - 'tests': ['tp5n']}}, 00:34:02 INFO - 'talos.zip': {'path': '', 00:34:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:34:02 INFO - Running post-action listener: _resource_record_post_action 00:34:02 INFO - ##### 00:34:02 INFO - ##### Running create-virtualenv step. 00:34:02 INFO - ##### 00:34:02 INFO - Running pre-action listener: _resource_record_pre_action 00:34:02 INFO - Running main action method: create_virtualenv 00:34:02 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 00:34:02 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 00:34:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 00:34:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:34:02 INFO - Using real prefix '/usr' 00:34:02 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 00:34:02 INFO - Installing distribute.............................................................................................................................................................................................done. 00:34:04 INFO - Installing pip.................done. 00:34:04 INFO - Return code: 0 00:34:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 00:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:04 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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:04 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 00:34:04 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 00:34:04 INFO - Using env: {'DISPLAY': ':0', 00:34:04 INFO - 'HOME': '/home/cltbld', 00:34:04 INFO - 'LANG': 'en_US.UTF-8', 00:34:04 INFO - 'LANGUAGE': 'en_US:en', 00:34:04 INFO - 'LOGNAME': 'cltbld', 00:34:04 INFO - 'MAIL': '/var/mail/cltbld', 00:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:04 INFO - 'MOZ_NO_REMOTE': '1', 00:34:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:04 INFO - 'NO_EM_RESTART': '1', 00:34:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:04 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:04 INFO - 'SHELL': '/bin/bash', 00:34:04 INFO - 'SHLVL': '1', 00:34:04 INFO - 'TERM': 'linux', 00:34:04 INFO - 'TMOUT': '86400', 00:34:04 INFO - 'USER': 'cltbld', 00:34:04 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:04 INFO - '_': '/tools/buildbot/bin/python'} 00:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:04 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 00:34:04 INFO - Cleaning up... 00:34:04 INFO - Return code: 0 00:34:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 00:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:04 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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:04 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 00:34:04 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 00:34:04 INFO - Using env: {'DISPLAY': ':0', 00:34:04 INFO - 'HOME': '/home/cltbld', 00:34:04 INFO - 'LANG': 'en_US.UTF-8', 00:34:04 INFO - 'LANGUAGE': 'en_US:en', 00:34:04 INFO - 'LOGNAME': 'cltbld', 00:34:04 INFO - 'MAIL': '/var/mail/cltbld', 00:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:04 INFO - 'MOZ_NO_REMOTE': '1', 00:34:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:04 INFO - 'NO_EM_RESTART': '1', 00:34:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:04 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:04 INFO - 'SHELL': '/bin/bash', 00:34:04 INFO - 'SHLVL': '1', 00:34:04 INFO - 'TERM': 'linux', 00:34:04 INFO - 'TMOUT': '86400', 00:34:04 INFO - 'USER': 'cltbld', 00:34:04 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:04 INFO - '_': '/tools/buildbot/bin/python'} 00:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:05 INFO - Downloading/unpacking psutil>=0.7.1 00:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:06 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 00:34:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 00:34:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 00:34:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:34:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:34:06 INFO - Installing collected packages: psutil 00:34:06 INFO - Running setup.py install for psutil 00:34:06 INFO - building 'psutil._psutil_linux' extension 00:34:06 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 00:34:07 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 00:34:07 INFO - building 'psutil._psutil_posix' extension 00:34:07 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 00:34:07 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 00:34:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:34:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:34:07 INFO - Successfully installed psutil 00:34:07 INFO - Cleaning up... 00:34:07 INFO - Return code: 0 00:34:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 00:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:07 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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:07 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 00:34:07 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 00:34:07 INFO - Using env: {'DISPLAY': ':0', 00:34:07 INFO - 'HOME': '/home/cltbld', 00:34:07 INFO - 'LANG': 'en_US.UTF-8', 00:34:07 INFO - 'LANGUAGE': 'en_US:en', 00:34:07 INFO - 'LOGNAME': 'cltbld', 00:34:07 INFO - 'MAIL': '/var/mail/cltbld', 00:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:07 INFO - 'MOZ_NO_REMOTE': '1', 00:34:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:07 INFO - 'NO_EM_RESTART': '1', 00:34:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:07 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:07 INFO - 'SHELL': '/bin/bash', 00:34:07 INFO - 'SHLVL': '1', 00:34:07 INFO - 'TERM': 'linux', 00:34:07 INFO - 'TMOUT': '86400', 00:34:07 INFO - 'USER': 'cltbld', 00:34:07 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:07 INFO - '_': '/tools/buildbot/bin/python'} 00:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:34:07 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 00:34:07 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 00:34:07 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 00:34:07 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 00:34:07 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 00:34:07 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 00:34:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:34:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 00:34:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:34:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:34:09 INFO - Installing collected packages: mozsystemmonitor 00:34:09 INFO - Running setup.py install for mozsystemmonitor 00:34:09 INFO - Successfully installed mozsystemmonitor 00:34:09 INFO - Cleaning up... 00:34:09 INFO - Return code: 0 00:34:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 00:34:09 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')]} 00:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:09 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')]} 00:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:09 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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:09 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 00:34:09 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 00:34:09 INFO - Using env: {'DISPLAY': ':0', 00:34:09 INFO - 'HOME': '/home/cltbld', 00:34:09 INFO - 'LANG': 'en_US.UTF-8', 00:34:09 INFO - 'LANGUAGE': 'en_US:en', 00:34:09 INFO - 'LOGNAME': 'cltbld', 00:34:09 INFO - 'MAIL': '/var/mail/cltbld', 00:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:09 INFO - 'MOZ_NO_REMOTE': '1', 00:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:09 INFO - 'NO_EM_RESTART': '1', 00:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:09 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:09 INFO - 'SHELL': '/bin/bash', 00:34:09 INFO - 'SHLVL': '1', 00:34:09 INFO - 'TERM': 'linux', 00:34:09 INFO - 'TMOUT': '86400', 00:34:09 INFO - 'USER': 'cltbld', 00:34:09 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:09 INFO - '_': '/tools/buildbot/bin/python'} 00:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:09 INFO - Downloading/unpacking blobuploader==1.2.4 00:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:11 INFO - Downloading blobuploader-1.2.4.tar.gz 00:34:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 00:34:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:34:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 00:34:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 00:34:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:11 INFO - Downloading docopt-0.6.1.tar.gz 00:34:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 00:34:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 00:34:11 INFO - Installing collected packages: blobuploader, requests, docopt 00:34:11 INFO - Running setup.py install for blobuploader 00:34:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:34:12 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 00:34:12 INFO - Running setup.py install for requests 00:34:12 INFO - Running setup.py install for docopt 00:34:12 INFO - Successfully installed blobuploader requests docopt 00:34:12 INFO - Cleaning up... 00:34:12 INFO - Return code: 0 00:34:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 00:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:12 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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:12 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 00:34:12 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 00:34:12 INFO - Using env: {'DISPLAY': ':0', 00:34:12 INFO - 'HOME': '/home/cltbld', 00:34:12 INFO - 'LANG': 'en_US.UTF-8', 00:34:12 INFO - 'LANGUAGE': 'en_US:en', 00:34:12 INFO - 'LOGNAME': 'cltbld', 00:34:12 INFO - 'MAIL': '/var/mail/cltbld', 00:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:12 INFO - 'MOZ_NO_REMOTE': '1', 00:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:12 INFO - 'NO_EM_RESTART': '1', 00:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:12 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:12 INFO - 'SHELL': '/bin/bash', 00:34:12 INFO - 'SHLVL': '1', 00:34:12 INFO - 'TERM': 'linux', 00:34:12 INFO - 'TMOUT': '86400', 00:34:12 INFO - 'USER': 'cltbld', 00:34:12 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:12 INFO - '_': '/tools/buildbot/bin/python'} 00:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:34:12 INFO - Running setup.py (path:/tmp/pip-hRmERs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:34:12 INFO - Running setup.py (path:/tmp/pip-y290cq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:34:12 INFO - Running setup.py (path:/tmp/pip-VOEVxW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:34:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:34:12 INFO - Running setup.py (path:/tmp/pip-f7_cXW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 00:34:13 INFO - Running setup.py (path:/tmp/pip-FJuhIQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:34:13 INFO - Running setup.py (path:/tmp/pip-z28xum-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:34:13 INFO - Running setup.py (path:/tmp/pip-kfh0dD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:34:13 INFO - Running setup.py (path:/tmp/pip-7KcyPT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 00:34:13 INFO - Running setup.py (path:/tmp/pip-b6wn9c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 00:34:13 INFO - Running setup.py (path:/tmp/pip-MOykwg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:34:13 INFO - Running setup.py (path:/tmp/pip-rMNQvG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:34:13 INFO - Running setup.py (path:/tmp/pip-yKyZQ3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:34:13 INFO - Running setup.py (path:/tmp/pip-nVxPFZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:34:13 INFO - Running setup.py (path:/tmp/pip-5pFeEv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:34:13 INFO - Running setup.py (path:/tmp/pip-hFfWBX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 00:34:13 INFO - Running setup.py (path:/tmp/pip-yPU2si-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 00:34:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 00:34:13 INFO - Running setup.py (path:/tmp/pip-eWmY9C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 00:34:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:34:13 INFO - Running setup.py install for manifestparser 00:34:14 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Running setup.py install for mozcrash 00:34:14 INFO - Running setup.py install for mozdebug 00:34:14 INFO - Running setup.py install for mozdevice 00:34:14 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Running setup.py install for mozfile 00:34:14 INFO - Running setup.py install for mozhttpd 00:34:14 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Running setup.py install for mozinfo 00:34:14 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Running setup.py install for mozInstall 00:34:14 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Running setup.py install for mozleak 00:34:14 INFO - Running setup.py install for mozlog 00:34:14 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 00:34:14 INFO - Running setup.py install for moznetwork 00:34:15 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 00:34:15 INFO - Running setup.py install for mozprocess 00:34:15 INFO - Running setup.py install for mozprofile 00:34:15 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 00:34:15 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 00:34:15 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 00:34:15 INFO - Running setup.py install for mozrunner 00:34:15 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 00:34:15 INFO - Running setup.py install for mozscreenshot 00:34:15 INFO - Running setup.py install for moztest 00:34:15 INFO - Running setup.py install for mozversion 00:34:15 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 00:34:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:34:15 INFO - Cleaning up... 00:34:15 INFO - Return code: 0 00:34:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 00:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:15 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 00:34:15 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 00:34:15 INFO - Using env: {'DISPLAY': ':0', 00:34:15 INFO - 'HOME': '/home/cltbld', 00:34:15 INFO - 'LANG': 'en_US.UTF-8', 00:34:15 INFO - 'LANGUAGE': 'en_US:en', 00:34:15 INFO - 'LOGNAME': 'cltbld', 00:34:15 INFO - 'MAIL': '/var/mail/cltbld', 00:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:15 INFO - 'MOZ_NO_REMOTE': '1', 00:34:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:15 INFO - 'NO_EM_RESTART': '1', 00:34:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:15 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:15 INFO - 'SHELL': '/bin/bash', 00:34:15 INFO - 'SHLVL': '1', 00:34:15 INFO - 'TERM': 'linux', 00:34:15 INFO - 'TMOUT': '86400', 00:34:15 INFO - 'USER': 'cltbld', 00:34:15 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:15 INFO - '_': '/tools/buildbot/bin/python'} 00:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:34:15 INFO - Running setup.py (path:/tmp/pip-MD_C4B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:34:16 INFO - Running setup.py (path:/tmp/pip-0mcsWG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:34:16 INFO - Running setup.py (path:/tmp/pip-7M8P3K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:34:16 INFO - Running setup.py (path:/tmp/pip-dRj65i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 00:34:16 INFO - Running setup.py (path:/tmp/pip-uW0T4b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:34:16 INFO - Running setup.py (path:/tmp/pip-Q0IXTq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:34:16 INFO - Running setup.py (path:/tmp/pip-1Sa4uT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:34:16 INFO - Running setup.py (path:/tmp/pip-vhMx5m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 00:34:16 INFO - Running setup.py (path:/tmp/pip-_XXmaI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 00:34:16 INFO - Running setup.py (path:/tmp/pip-SfSyqX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:34:16 INFO - Running setup.py (path:/tmp/pip-zHcdM_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:34:16 INFO - Running setup.py (path:/tmp/pip-4c7FtF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:34:16 INFO - Running setup.py (path:/tmp/pip-aVBjTE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:34:16 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)) 00:34:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:34:16 INFO - Running setup.py (path:/tmp/pip-YTcrOv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:34:17 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)) 00:34:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:34:17 INFO - Running setup.py (path:/tmp/pip-8Xzw9X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:34:17 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)) 00:34:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 00:34:17 INFO - Running setup.py (path:/tmp/pip-rrwQ8t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 00:34:17 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)) 00:34:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 00:34:17 INFO - Running setup.py (path:/tmp/pip-D62_96-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 00:34:17 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)) 00:34:17 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)) 00:34:17 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)) 00:34:17 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)) 00:34:17 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)) 00:34:17 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)) 00:34:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 00:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:34:18 INFO - Downloading blessings-1.5.1.tar.gz 00:34:18 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 00:34:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 00:34:18 INFO - Installing collected packages: blessings 00:34:18 INFO - Running setup.py install for blessings 00:34:19 INFO - Successfully installed blessings 00:34:19 INFO - Cleaning up... 00:34:19 INFO - Return code: 0 00:34:19 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 00:34:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 00:34:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 00:34:19 INFO - Reading from file tmpfile_stdout 00:34:19 INFO - Current package versions: 00:34:19 INFO - argparse == 1.2.1 00:34:19 INFO - blessings == 1.5.1 00:34:19 INFO - blobuploader == 1.2.4 00:34:19 INFO - docopt == 0.6.1 00:34:19 INFO - manifestparser == 1.1 00:34:19 INFO - mozInstall == 1.12 00:34:19 INFO - mozcrash == 0.16 00:34:19 INFO - mozdebug == 0.1 00:34:19 INFO - mozdevice == 0.47 00:34:19 INFO - mozfile == 1.2 00:34:19 INFO - mozhttpd == 0.7 00:34:19 INFO - mozinfo == 0.9 00:34:19 INFO - mozleak == 0.1 00:34:19 INFO - mozlog == 3.1 00:34:19 INFO - moznetwork == 0.27 00:34:19 INFO - mozprocess == 0.22 00:34:19 INFO - mozprofile == 0.27 00:34:19 INFO - mozrunner == 6.11 00:34:19 INFO - mozscreenshot == 0.1 00:34:19 INFO - mozsystemmonitor == 0.0 00:34:19 INFO - moztest == 0.7 00:34:19 INFO - mozversion == 1.4 00:34:19 INFO - psutil == 3.1.1 00:34:19 INFO - requests == 1.2.3 00:34:19 INFO - wsgiref == 0.1.2 00:34:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 00:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:34:19 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 0x7fc8ce03cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245c7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, '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': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', '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 00:34:19 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 00:34:19 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 00:34:19 INFO - Using env: {'DISPLAY': ':0', 00:34:19 INFO - 'HOME': '/home/cltbld', 00:34:19 INFO - 'LANG': 'en_US.UTF-8', 00:34:19 INFO - 'LANGUAGE': 'en_US:en', 00:34:19 INFO - 'LOGNAME': 'cltbld', 00:34:19 INFO - 'MAIL': '/var/mail/cltbld', 00:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:19 INFO - 'MOZ_NO_REMOTE': '1', 00:34:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:19 INFO - 'NO_EM_RESTART': '1', 00:34:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:19 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:19 INFO - 'SHELL': '/bin/bash', 00:34:19 INFO - 'SHLVL': '1', 00:34:19 INFO - 'TERM': 'linux', 00:34:19 INFO - 'TMOUT': '86400', 00:34:19 INFO - 'USER': 'cltbld', 00:34:19 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:19 INFO - '_': '/tools/buildbot/bin/python'} 00:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 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)) 00:34:19 INFO - Cleaning up... 00:34:19 INFO - Return code: 0 00:34:19 INFO - Running post-action listener: _resource_record_post_action 00:34:19 INFO - Running post-action listener: _start_resource_monitoring 00:34:19 INFO - Starting resource monitoring. 00:34:19 INFO - ##### 00:34:19 INFO - ##### Running install step. 00:34:19 INFO - ##### 00:34:19 INFO - Running pre-action listener: _resource_record_pre_action 00:34:19 INFO - Running main action method: install 00:34:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 00:34:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 00:34:19 INFO - Reading from file tmpfile_stdout 00:34:19 INFO - Detecting whether we're running mozinstall >=1.0... 00:34:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 00:34:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 00:34:19 INFO - Reading from file tmpfile_stdout 00:34:19 INFO - Output received: 00:34:19 INFO - Usage: mozinstall [options] installer 00:34:19 INFO - Options: 00:34:19 INFO - -h, --help show this help message and exit 00:34:19 INFO - -d DEST, --destination=DEST 00:34:19 INFO - Directory to install application into. [default: 00:34:19 INFO - "/builds/slave/test-pgo"] 00:34:19 INFO - --app=APP Application being installed. [default: firefox] 00:34:19 INFO - mkdir: /builds/slave/test-pgo/build/application 00:34:19 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'] 00:34:19 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 00:34:34 INFO - Reading from file tmpfile_stdout 00:34:34 INFO - Output received: 00:34:34 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 00:34:34 INFO - Running post-action listener: _resource_record_post_action 00:34:34 INFO - ##### 00:34:34 INFO - ##### Running run-tests step. 00:34:34 INFO - ##### 00:34:34 INFO - Running pre-action listener: _resource_record_pre_action 00:34:34 INFO - Running main action method: run_tests 00:34:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 00:34:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 00:34:34 INFO - Python 2.7.3 00:34:34 INFO - Return code: 0 00:34:34 INFO - grabbing minidump binary from tooltool 00:34:34 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')]} 00:34:34 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 0x24a0d70>, '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 0x24a1b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a1fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 00:34:34 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 00:34:34 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 00:34:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:34:34 INFO - Return code: 0 00:34:34 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 00:34:34 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 00:34:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 00:34:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 00:34:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 00:34:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 00:34:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost 00:34:34 INFO - Using env: {'DISPLAY': ':0', 00:34:34 INFO - 'HOME': '/home/cltbld', 00:34:34 INFO - 'LANG': 'en_US.UTF-8', 00:34:34 INFO - 'LANGUAGE': 'en_US:en', 00:34:34 INFO - 'LOGNAME': 'cltbld', 00:34:34 INFO - 'MAIL': '/var/mail/cltbld', 00:34:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 00:34:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 00:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:34:34 INFO - 'MOZ_NO_REMOTE': '1', 00:34:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 00:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:34:34 INFO - 'NO_EM_RESTART': '1', 00:34:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:34:34 INFO - 'PWD': '/builds/slave/test-pgo', 00:34:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 00:34:34 INFO - 'SHELL': '/bin/bash', 00:34:34 INFO - 'SHLVL': '1', 00:34:34 INFO - 'TERM': 'linux', 00:34:34 INFO - 'TMOUT': '86400', 00:34:34 INFO - 'USER': 'cltbld', 00:34:34 INFO - 'XDG_SESSION_COOKIE': '1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310', 00:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:34:34 INFO - '_': '/tools/buildbot/bin/python'} 00:34:34 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] with output_timeout 3600 00:34:34 INFO - mozversion INFO | application_buildid: 20151120223009 00:34:34 INFO - mozversion INFO | application_changeset: de4585e9617cb5b888ebec7296080bc4c33acaa5 00:34:34 INFO - mozversion INFO | application_display_name: Nightly 00:34:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:34:34 INFO - mozversion INFO | application_name: Firefox 00:34:34 INFO - mozversion INFO | application_remotingname: firefox 00:34:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 00:34:34 INFO - mozversion INFO | application_vendor: Mozilla 00:34:34 INFO - mozversion INFO | application_version: 45.0a1 00:34:34 INFO - mozversion INFO | platform_buildid: 20151120223009 00:34:34 INFO - mozversion INFO | platform_changeset: de4585e9617cb5b888ebec7296080bc4c33acaa5 00:34:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 00:34:34 INFO - mozversion INFO | platform_version: 45.0a1 00:34:34 INFO - 2015-11-21 00:34:34,526 DEBUG : using testdate: 1448094874 00:34:34 INFO - 2015-11-21 00:34:34,526 DEBUG : actual date: 1448094874 00:34:34 INFO - 2015-11-21 00:34:34,574 INFO : starting webserver on 'localhost:33007' 00:34:34 INFO - 2015-11-21 00:34:34,575 INFO : Starting test suite talos-linux64-ix-029.e 00:34:34 INFO - 2015-11-21 00:34:34,575 INFO : Starting test dromaeo_css 00:34:34 INFO - 2015-11-21 00:34:34,575 DEBUG : operating with platform_type : linux_ 00:34:34 INFO - 2015-11-21 00:34:34,576 INFO : Initialising browser for dromaeo_css test... 00:34:34 INFO - 2015-11-21 00:34:34,592 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFLdO5r/profile http://localhost:33007/getInfo.html 00:34:39 INFO - 2015-11-21 00:34:39,800 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 00:34:39 INFO - 2015-11-21 00:34:39,800 DEBUG : BROWSER_OUTPUT: colorDepth:24 00:34:39 INFO - 2015-11-21 00:34:39,800 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 00:34:39 INFO - 2015-11-21 00:34:39,800 DEBUG : BROWSER_OUTPUT: __metrics 00:34:40 INFO - 2015-11-21 00:34:40,845 INFO : Browser initialized. 00:34:40 INFO - 2015-11-21 00:34:40,846 INFO : Running cycle 1/1 for dromaeo_css test... 00:34:40 INFO - 2015-11-21 00:34:40,846 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFLdO5r/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 00:34:52 INFO - 2015-11-21 00:34:52,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 141602816 00:34:52 INFO - 2015-11-21 00:34:52,622 DEBUG : BROWSER_OUTPUT: 00:35:42 INFO - 2015-11-21 00:35:42,245 DEBUG : BROWSER_OUTPUT: 1448094942239 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] 00:37:51 INFO - 2015-11-21 00:37:51,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33007/tests/dromaeo/cssquery-ext.html) 00:37:52 INFO - 2015-11-21 00:37:52,698 DEBUG : BROWSER_OUTPUT: RSS: Main: 147603456 00:37:52 INFO - 2015-11-21 00:37:52,699 DEBUG : BROWSER_OUTPUT: 00:40:54 INFO - 2015-11-21 00:40:54,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/dromaeo/cssquery-ext.html (next: http://localhost:33007/tests/dromaeo/cssquery-jquery.html) 00:40:55 INFO - 2015-11-21 00:40:55,227 DEBUG : BROWSER_OUTPUT: RSS: Main: 142114816 00:40:55 INFO - 2015-11-21 00:40:55,227 DEBUG : BROWSER_OUTPUT: 00:43:56 INFO - 2015-11-21 00:43:56,918 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33007/tests/dromaeo/cssquery-mootools.html) 00:43:57 INFO - 2015-11-21 00:43:57,507 DEBUG : BROWSER_OUTPUT: RSS: Main: 143863808 00:43:57 INFO - 2015-11-21 00:43:57,507 DEBUG : BROWSER_OUTPUT: 00:46:59 INFO - 2015-11-21 00:46:59,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33007/tests/dromaeo/cssquery-prototype.html) 00:46:59 INFO - 2015-11-21 00:46:59,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 141512704 00:46:59 INFO - 2015-11-21 00:46:59,734 DEBUG : BROWSER_OUTPUT: 00:50:02 INFO - 2015-11-21 00:50:02,034 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33007/tests/dromaeo/cssquery-yui.html) 00:50:02 INFO - 2015-11-21 00:50:02,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 141975552 00:50:02 INFO - 2015-11-21 00:50:02,656 DEBUG : BROWSER_OUTPUT: 00:53:04 INFO - 2015-11-21 00:53:04,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/dromaeo/cssquery-yui.html (next: http://localhost:33007/tests/dromaeo/cssquery-dojo.html) 00:53:05 INFO - 2015-11-21 00:53:05,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 144785408 00:53:05 INFO - 2015-11-21 00:53:05,070 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,071 DEBUG : BROWSER_OUTPUT: __start_tp_report 00:53:05 INFO - 2015-11-21 00:53:05,071 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 00:53:05 INFO - 2015-11-21 00:53:05,071 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 00:53:05 INFO - 2015-11-21 00:53:05,071 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 00:53:05 INFO - 2015-11-21 00:53:05,072 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3231.8384080795963;3294.9950265169127;3315.552400425942;3333.4333163362226;3344.782589131706;5305.946940530594;5503.394626591072;5533.972330138348;5543.501084902363;5552.555795536357;5446.373667028283;5457.181422786584;5486.396496385394;5506.449355064491;5552.77788888444;4617.2381557043045;4923.040007198598;5095.414027386861;5108.948910510884;5115.258287548303;4894.632902532307;5052.469490703478;5091.261767043777;5114.28400023997;5142.228665700138;4917.680350777188;4986.052649996525;5011;5034.798608055673;5035.345405097332;5024.773885175176;5079.771410286546;5084.339035925343;5113.130767769489;5145.253938178943;4967.801287948478;5022.029944775612;5041.949580504184;5059.974700126476;5090.618203634705;5246.501582349671;5248.895022099537;5261.184516399964;5284;5297;5870.794522191745;5872.383399743008;5875.471207591295;5877.970610146922;5887.6173048752125;5830.096335068071;5864.120381942743;5880.882382352372;5894.528437724972;5901.3508514063415;5585;5646.632968857011;5654.5193658538665;5656.91514627281;5706.400827913092;6001.639901605918;6004.249468816398;6089.5737298388685;6091.786787462417;6094.9695251524345;5742.196092547047;5808.36108028125;5813.6221145625395;5890.2342695449315;5917.55618328627;5976.85057873548;6004.129401236796;6017.308009578867;6031.125486804476;6045.063015232646;5983.222181116427;5988.071848863346;5991.7902873399225;5995.070764031582;5996.850078747979;5777.653340799566;5849.53203743708;5898.056310990221;5899.823005309848;5986.610870293417;4533;4559.361689363492;4570.862874113782;4588.036512332381;4618.422697162855;4088.386741988666;4091.263572556909;4092;4103.7742924138865;4130.235906357333;5683.857903552362;5702.030654788696;5708.9714551427805;5723.77104915807;5776.249087618583;5808.099744539603;5897.144913987448;5901.232839730807;5972.761089556456;6025.0359942409;5652.491275785199;5679.971600142056;5682.772689092472;5695.45893140151;5706.543476521868;5115.948840511622;5165.61257905651;5211.348581427322;5222.00781851447;5272.20916862466;5998;6009.038553831453;6013.037913933749;6014.308354539196;6028.909566356509;5928.79249226275;5994.67029313392;5999.5800293979;6006.579539432372;6049.062395328731;6007.918574656604;6102.4507794298725;6131.570790044654;6149.569530132848;6199.690015499297;5631.718414079198;5682.147677848356;5747.7413516391025;5890.381509941394;5901.409859014236;5828.184054232495;5853.414658534283;5868.4424979626865;5874.735636896265;5931.3772053935445;5857.619254748428;5860.619059761102;5872.0898260769645;5942.554308426799;5975.312839023568;5822.243108395882;5849.093390524476;5854.180414742024;5857.941420585739;5878.764849405976;5665.178549110438;5741.483266506035;5750.367459579526;5779.393163717918;5808.390119037609;5822.475977162076;5849.795257165979;5860.531157507304;5866.677332746569;5873.765049398147;5706.087026075808;5732.71336433185;5735.197072409783;5735.4551317624755;5742.655440673573 00:53:05 INFO - 2015-11-21 00:53:05,073 DEBUG : BROWSER_OUTPUT: |1;ext.html;554.8919483560256;589.3899813692829;590.7695998560561;603.9818805435839;608.0241212853373;38744.41883371748;39740.40389394154;39849.40225896614;39981.20037599246;40005.39991900124;41873.790631046875;42173.57826421735;42491.78754106225;42506.36240456396;42524.787376063156;8719.389642725027;8845.4250473719;8845.955770221171;8857.867131993025;8857.911420885774;8659.740207793777;8734.694285700001;8767.254783343424;8770.386072974863;8805.20753132218;8697.086805885387;8768.60541275641;8785.989611194698;8787.165219304188;8788.384813063089;8679;8732.738017859474;8756.562171891444;8769;8770.736877893672;8517.74446766598;8647.827043459158;8650.783730406727;8665.740027799176;8704.434211776213;39913.201735965406;39939.8003009986;40215;40456.39315410271;40468;3152.921176970571;3164.9841750791097;3226.3063441359986;3328.517364982064;3512.3150985557827;28563.85718071417;29214.41571168586;29673.851630741923;29803.70196298031;29810.70189298101;3774.9433758493647;3812.1613245086037;3864.768113913174;3892.2020985698146;4009.719319647626;273837;275079;275106;275571;276513;49742.27448392397;50558.24162637563;56519;57390;57419.42580574224;53524.464755352725;53650.19524707132;53862.922741545735;53864.19203711947;53927;14882.702345953021;14994.850051499345;15038.17290049036;15049.623759406102;15100;11315.660530184108;11385.861413858547;11404.429778511041;11476.311421314747;11567.652970410902;18844.905775471034;18870;19053.42839714811;19130.904345478182;19182.712259316122;7900.076971520574;8036.8748375227515;8038.799030024296;8075.798105047421;8157.796055098552;981.1365997921783;981.4111533080237;1004.4425343934041;1019.0166489337824;1121.243160866412;25557.616635750477;26935.461290774074;27328;27497.17508474749;27515;1054.9103326217205;1059.51262419288;1070.29895418501;1152.4295473740385;1195.8564972203392;14257.287135643177;14290.714185716435;14323.92838035824;14553.345099470549;14618.780718289234;324361;327931;328632;329073;329859;51018.48981510212;51036;51046.405823475325;51278;51360;1286.9163504372373;1308.5681725030718;1314.2311747627555;1314.605618314521;1337.6856438736888;25897;27189.456210875276;27329.86335068312;27569.862150689118;28382;44070.33894491649;44146;44341.11317773562;44376.556234437245;44391.77804110959;225993;226799;227652;227788;228173;30347.848260758554;31528.527072094395;31898;32042.359152816345;32043.839780800947;40584.39123413092;40717.1856562873;41363.379549307385;41675.37486937759;42532.574674252864;34710;35183.64816351804;35392.1519620095;35531.64468355283;35888.10279742923;34657.480137798455;35665.64334356727;36148.4577731329;36387.81806090953;36399;34896.30207395888;34907.127321817155;35251.47122793212;35339.29321413609;35350.29299413946;31174.844125779226;32348.676513234568;32769.83615082005;32940.835295823366;32966.505502417;24399;24832.627510586994;25478;25993.6100958482;26412.735872641028 00:53:05 INFO - 2015-11-21 00:53:05,076 DEBUG : BROWSER_OUTPUT: |2;jquery.html;341.6839423533231;343.20376725995686;344.6501800672317;345.25080575151935;350.726433381962;16319.020858748469;16624.335026598936;17362.131893405327;17704;18384.632307353844;10399.792004159912;11113.777724445506;15915.52253432396;16034.23623401425;16060.839391606081;4565.315202719594;4945.4065512138595;5074.416442109168;5098.56662183713;5126.948730512684;4449.4660640723;5070.112730272206;5095.770690318945;5111.514406131411;5761.884762304751;4940.678855874375;5090.287359769635;5105.489451054883;5665.3768085510765;5706.372299047122;4975.875603109915;5043.470435604264;5050.419201791804;5055.494450554934;5078.035173317076;5020.447750747451;5074.746262686851;5081.415637201695;5092.872678183038;5103.668261563023;12009.519619215222;12138.635840924786;16059.357625695075;20004.49988750293;20064.197432102843;2009.5578972626;2085.9687104693444;2092.1840482211796;2146.8604540704805;2221.955560888773;8993.730188094367;9440.858387124199;10050.748731281703;10455;13095.083344165912;1639.1148779659088;1704.5142134491734;1719.8796084273981;1747.877648564601;1886.6604011277964;60313.39686603122;62957.99999999954;64054;64091.67954160246;64713.67643161801;10187.592496300065;10315.484225788681;10407.94796026015;10408;10419.42693151884;13457.663558410923;13489.932550337186;13517.256550889599;13527.864721352857;13558.93220533891;7450.217727138681;7526.5860377679755;7526.811829704301;7560.3573696236435;7565.6973721050545;7211.783646490614;7223.783286501413;7242.058532390755;7281.817954551178;7291.963540182265;4207.8316867325575;4234.872953811329;4400.9559904401185;4462.933056004163;4533.523979982055;1841.3555255660413;1861.376438892986;1887.0467718021252;1904.3620387170176;1916.7124931260423;396.23724330663356;400.1676512853258;412.51117425850464;420.7959139817186;424.1982652786175;15864.682706345808;16658.250378732988;17685.646287074185;17864.732029019575;17961.820381796275;923.8845144356956;955.441066975827;1011.7723512209841;1024.6157690865925;1071.908887744535;9342.766430839283;9694.660686875926;9883.369244074791;9906.306558540977;10045.648402305884;565610;568669;575498;590008;590374;79417.60291198622;80372;80743.59628201822;81065;82031.58984205041;1156.1848896528095;1156.653004098767;1170.2101081769772;1179.0508640544272;1190.125257935434;9748.51257437111;15572.610684732972;15596.440355964312;15708.21458927072;15758.290876910338;34657.65342346544;34876.825615871756;35912.4613130798;36450.27099457943;36576;49332.75333623309;51402.97194056173;52341.21488177757;52617.7369113152;54211;13974.860251397762;14343.139411635335;14536.563903082924;14542.909281803695;14924;17553.736693949857;17643.55891102733;17670.91164544169;17875.553111172325;18260.36088736888;9351.205147562534;9589.376690515093;9714.902850971683;14501.564953051426;15155;7729;9496.78339548653;9713.417194968324;9713.882903466192;9777.41335519871;9526.952365238129;9729.075737804897;10026.548805303633;10284.382937023802;10364.119049880845;14747.55757327282;14979;15106.866984975952;15121.697566048837;15241.390344386544;14929.552113436614;14984.452643050925;15336.156511391535;15630.374785008473;15996.68006639884 00:53:05 INFO - 2015-11-21 00:53:05,076 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1481.511101336559;1741.9738703919443;1771.6633839570482;1805.2057094878253;1806.4761219246416;6832.965835170818;6867.793966181022;7754.495957762741;7969.840603187933;7983.401244906633;7537.62311884405;7600.1639819619795;7688.500247483909;7755.961220193905;7771.455998080137;2260.7852254035884;2292.5529521743265;2314.7916687498123;2317.3627252505526;2320.7447180810095;2296.150424342987;2323.186884590397;2334.5447637710654;2337.91817286395;2342.5666251743482;2264.0377839418265;2265.6714776357417;2308.1113771198125;2313;2315.5716192504356;2285.3029825903095;2317.2932255662017;2328.196772113618;2346.085026839525;2357.823163262745;2340.6840076589783;2362.14962613459;2363.3264519612;2364.0189321431585;2372.7034120734907;7743.264389883008;7851.175626559186;7854.293113619745;7861.842763144705;7880.0937892142565;4597.356370108188;4690.273007683814;4700.647451441121;4703.929441058387;4704.1062198182235;3205.9198520036953;3219.098652377338;3270.2641905571295;3288.3094550144497;3291.308825146722;1127.3405058041055;1130.4856290387854;1136.3409222650844;1157.9478923448464;1164.9825252621217;10462.895371046268;10546.683599492027;10589.682309530726;10607.628732994308;10616.62841800541;4240.936385954213;4285.6142947134285;4293.355996600472;4345.891797591594;4361.672874534423;9106.31702622306;9153.130452606993;9165.31260155491;9172.44965302084;9178.67874624385;4682.719036857799;4708.270218116198;4723.7874295656775;4742;4755.643326750438;4242.363645453207;4302.182585308781;4338.067315527113;4354.586314300136;4366.43236379275;3658.835352409148;3674.393725035393;3700;3704.7406681532575;3725.0501122213664;1146.432515904616;1157.2188772578645;1162.4768854015726;1164.4119719541748;1165.5337864854125;1351.770199066161;1354.4582167133028;1365.8156148919968;1372.7529044772036;1376.951806686761;7392.186859445456;7506.249375062559;7558.546487210675;7570.886436703454;7572.053493313336;463.04612498253704;467.38538821449686;468.3489948970933;471.4931448692669;476.2713049034982;5848.736806843703;5889.087191485327;5912.260967379078;5913.674747888821;5922.2597175353085;9028.774280643036;9354.251659867194;9449.480278584606;9525.333226674065;9585.185259253041;9333.813323733622;9381.62473501066;9477.66828161025;9477.810443791224;9497.335186536877;7222.422206223489;7319.377852882458;7337.339639432476;7373.705051797975;7428.034355533854;6979.267176946348;7012.859742805013;7050.400715939202;7092.574445533284;7111.573305601681;7981.241782030698;8079.434439589172;8307.792305192419;8317.043539992977;8323.001239851257;8503.404761666625;8508.149185081442;8563.914360856561;8590.14098590136;8596.35527335465;6549.967250163719;6780.423663988616;6955.061066756126;7102.751403700846;7104.040954471287;7596.582187979714;7621.428392870446;7631.122420921665;7642.8089297768;7644.273793989562;7077.6461176941975;7211.927880721126;7272.23641517633;7327.120745510573;7377.151627562899;6931.133608298963;6970.895436568557;7080.929190708027;7106.502544821813;7114.893276600752;7021.964890175516;7160.10498687664;7168.964155179191;7181.24596917337;7186.748463803742;6644.269130395749;6678;6680.164979377578;6685.164354455693;6696.933030669631;6469.547131700736;6542.149520562297;6559.508036897348;6562.081308616893;6581.045748366364 00:53:05 INFO - 2015-11-21 00:53:05,080 DEBUG : BROWSER_OUTPUT: |4;prototype.html;316.0423915535927;320.9695078967498;326.8431153046537;327.7279857718094;333.3965522404448;6272.027835685476;6279.686015699214;6281.466075383592;6305.085762564426;6417.839554011152;6154.599951003182;6307.526935479835;6314.715837787299;6345.270293916201;6367.235931688192;4781.90436191276;4797.99242159147;4799.952000479985;4815.494373090827;4817;4827.179379505475;4835.709857408567;4837.371141751567;4838;4844.781984810692;4683.1336202802595;4747.691400059002;4763.595094416962;4767.547083027124;4780.350778981263;4728.243481043017;4732.858014259577;4750.453697824725;4756.762161891909;4778.235482322812;4135.276326642856;4138.420621113047;4157.896052598679;4162.895927601804;4165.729227600226;5997.400259973968;6021.307549631761;6022.337542870325;6034.396560343975;6035.7283922223605;5433.8369848904595;5544.306961629796;5569.693666848321;5571.275734154575;5580.553555715533;6630.73477060917;6631.568948018364;6656.36764507371;6671.799846004627;6692.966535167342;4569.268916973301;4570.428696412949;4579.290210017452;4580.267157254823;4583.610393116589;6778.661066946683;6925.272846351111;6929.896051559231;6984.126984126984;6986.021956925983;4558.4529856416775;4593.356930029799;4610.3084537319;4613.261878099555;4613.377194078824;4672.322513235562;4672.719636821802;4672.813087476531;4673.929891051637;4682.484926658065;5471.6169868108855;5475.09660905954;5480.862978425571;5485.067538518461;5489.1217405215775;6621.635810030494;6756.763513277012;6757.155355580552;6758.662066896635;6762.594244345355;1605.6307049378474;1612.6532795449034;1640.2946732904727;1641.3762770147507;1645.4816732729153;1079.0881704959297;1083.2525557365393;1093.9890601094046;1094.7263184203948;1100.526773487408;6435.131257280301;6449.355064493513;6466.612003279818;6474.449671777952;6493.448056915121;6830.829229269209;6883.105196324546;6887.586744795228;6897.172339319213;6897.310268973062;6407.583507072025;6429.38920802523;6475.546711730228;6477.805665830033;6484.805455836333;3796.3735983562956;3827.483289755903;3828.8085595720104;3832.885013449545;3834.118152824866;7245.529040612343;7284.125904891447;7323.450741194431;7324.560526368435;7341.008963789926;7072.222055573883;7187.496875218684;7215.386692131227;7230.819229519199;7249.420046396275;7304.96347518259;7305.41556675454;7305.561666300039;7307.744228951961;7318.780436586911;6986.825329366603;6997.300269972962;7009.228984811769;7032.331928466788;7042.647867606702;6746.8650626988165;6927.203371612329;6954.617496037564;6977.2673869243;6985.057017302803;7254.600996945219;7300.707971681073;7321.121465424183;7324.707011719472;7339.082614673166;4580.908381832278;4630.768461576975;4634.907301854011;4644.419447569054;4647.279671650899;6784.93215067843;6818.624975626388;6942.965285173744;6990.685419156252;6994.160700715946;6798.76204332846;6815.284395138439;6815.829604259933;6823.385895269251;6829.829254268683;6789.558678685869;6790.354916282945;6794.796156115324;6799.694013769491;6806.7957961261245;6514.674266286762;6623.933760662524;6662.866742665023;6672;6673.699683514156;6827.726890924308;6834.2482326943;6837.384635382839;6838.897416538656;6863.38229559342;6669.866602668017;6674.566153200027;6676.499262555425;6684.131062961784;6686.732530698912 00:53:05 INFO - 2015-11-21 00:53:05,081 DEBUG : BROWSER_OUTPUT: |5;yui.html;829.4442723375338;830.6843399508186;832.5129799067546;836.0886633569409;842.3429724814639;12618.62144135675;12918.418671159809;12960.675983100427;15551.22243887804;15754.212289385543;14897;14965.625859353522;14969.47606833761;14989.625259368495;15042.548723538284;803.4135081390589;806.238104990784;806.7620052084649;811.7889348769304;813.8128230506987;787.7203592724586;799.4124318625805;801.1667865419956;802.5144787403596;803.8593246181924;811.5374236685092;822.3667775812621;824.9216324449197;826.2150956591232;831.222806675757;815.6166601697222;825.1088824070019;829.531314807132;831.1356189562907;831.2477208126655;610.4566935427473;610.548194336188;610.7282259394572;611.3672349118641;612.8498517863152;12429.813552796715;12446.937765311206;12449.688757781129;12532.686682833002;12537.93731031339;11812.822807657893;11890.346030968294;12234.449449774782;12334.81497777534;12780.04149688777;10002.199824014062;10102.14131798798;10105.9494702526;10339.689809305733;10361.37831730091;3588.1747198144312;3589.9102522437147;3598.208394153282;3641.035125691694;3710.0353907984118;48157.75921120372;48456.75771621155;48816;49034;49150.26274605884;9407.247420206368;9422.152006319327;9491.810163796685;9550.426974381558;9559.665411710557;17235.22441490136;17408.955462672282;17410.21654025572;17435.73846392305;17462.650746985244;6752.696128674222;6794.796156115324;6865.176178858569;6947.270536593584;7097.077379940678;3933.645971862546;3988.1824226033596;4025.5370632377067;4051.716379853382;4064.207479541461;3099.070278916316;3109.129443755752;3138.7959782614057;3145;3151.5430262611794;504.9798008079709;525.6635753118007;532.2761044978826;533.1922137960992;534.2253732088487;523.9554637855825;572.9169270455844;603.6167033933484;612.8927437698385;676.6143298320009;7636.465447418738;7820.452568320276;8105.351571874236;8134.186581341937;8398.454100483448;554.3936691819687;642.4218203616689;643.9195100612424;676.1345477788439;683.111954459201;3523.9295214096087;3621.9818900905325;3653.5514816349164;3678.9448158277646;3838.059675379494;56639.71680141573;61399.386006139364;61629.07556386658;61710.38289617136;61876.38123618706;47186.5281347189;47561;47566.52433475621;47738.28392574114;48185.277220841715;46002.53997459982;47819.760901195965;48275.75862120667;48468;48770.75614621975;7837.37301015906;7994.080680721559;8128.75613731589;8256.504609723435;8529.360297977553;32834.67165328412;33874.3225135491;34204.657953420145;36706.26587468289;36752.816235918646;41692.58307416887;42384;42889.57110428856;47043.76478117587;47598.28602570895;9942.950285248528;10757.30077544957;11055.89441055888;11068.667939961813;11521.078313735048;12162.452689629165;12282.754344912873;12303.815442768187;12375;12683.87316126827;10547.367157970546;10713.303635263683;10722.839157412802;10903.890961090605;11439.714007149887;11874.287542747463;12160.695982600151;12465.813012804634;12702.745945081231;12759;11163.832542512031;11208.887911120784;11249.381284029458;11603.558371437543;11609.165706109306;8945.552722363725;9159.725208243763;9181.862272066059;9221.538923053686;9517.428954262767;8751.168638979287;9035.759739713081;9141.63433462654;9176.862347064933;9461.290403219924 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: __end_tp_report 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: __start_cc_report 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,302 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: __end_cc_report 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: __startTimestamp1448095985070__endTimestamp 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: Number of tests: 6 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5513.20 Median:5742.20 stddev:611.39 (10.6%) stddev-sans-first:586.49 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: Values: 3231.8 3295.0 3315.6 3333.4 3344.8 5305.9 5503.4 5534.0 5543.5 5552.6 5446.4 5457.2 5486.4 5506.4 5552.8 4617.2 4923.0 5095.4 5108.9 5115.3 4894.6 5052.5 5091.3 5114.3 5142.2 4917.7 4986.1 5011.0 5034.8 5035.3 5024.8 5079.8 5084.3 5113.1 5145.3 4967.8 5022.0 5041.9 5060.0 5090.6 5246.5 5248.9 5261.2 5284.0 5297.0 5870.8 5872.4 5875.5 5878.0 5887.6 5830.1 5864.1 5880.9 5894.5 5901.4 5585.0 5646.6 5654.5 5656.9 5706.4 6001.6 6004.2 6089.6 6091.8 6095.0 5742.2 5808.4 5813.6 5890.2 5917.6 5976.9 6004.1 6017.3 6031.1 6045.1 5983.2 5988.1 5991.8 5995.1 5996.9 5777.7 5849.5 5898.1 5899.8 5986.6 4533.0 4559.4 4570.9 4588.0 4618.4 4088.4 4091.3 4092.0 4103.8 4130.2 5683.9 5702.0 5709.0 5723.8 5776.2 5808.1 5897.1 5901.2 5972.8 6025.0 5652.5 5680.0 5682.8 5695.5 5706.5 5115.9 5165.6 5211.3 5222.0 5272.2 5998.0 6009.0 6013.0 6014.3 6028.9 5928.8 5994.7 5999.6 6006.6 6049.1 6007.9 6102.5 6131.6 6149.6 6199.7 5631.7 5682.1 5747.7 5890.4 5901.4 5828.2 5853.4 5868.4 5874.7 5931.4 5857.6 5860.6 5872.1 5942.6 5975.3 5822.2 5849.1 5854.2 5857.9 5878.8 5665.2 5741.5 5750.4 5779.4 5808.4 5822.5 5849.8 5860.5 5866.7 5873.8 5706.1 5732.7 5735.2 5735.5 5742.7 00:53:05 INFO - 2015-11-21 00:53:05,082 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,084 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44437.91 Median:35371.22 stddev:73083.00 (206.6%) stddev-sans-first:73213.75 00:53:05 INFO - 2015-11-21 00:53:05,084 DEBUG : BROWSER_OUTPUT: Values: 554.9 589.4 590.8 604.0 608.0 38744.4 39740.4 39849.4 39981.2 40005.4 41873.8 42173.6 42491.8 42506.4 42524.8 8719.4 8845.4 8846.0 8857.9 8857.9 8659.7 8734.7 8767.3 8770.4 8805.2 8697.1 8768.6 8786.0 8787.2 8788.4 8679.0 8732.7 8756.6 8769.0 8770.7 8517.7 8647.8 8650.8 8665.7 8704.4 39913.2 39939.8 40215.0 40456.4 40468.0 3152.9 3165.0 3226.3 3328.5 3512.3 28563.9 29214.4 29673.9 29803.7 29810.7 3774.9 3812.2 3864.8 3892.2 4009.7 273837.0 275079.0 275106.0 275571.0 276513.0 49742.3 50558.2 56519.0 57390.0 57419.4 53524.5 53650.2 53862.9 53864.2 53927.0 14882.7 14994.9 15038.2 15049.6 15100.0 11315.7 11385.9 11404.4 11476.3 11567.7 18844.9 18870.0 19053.4 19130.9 19182.7 7900.1 8036.9 8038.8 8075.8 8157.8 981.1 981.4 1004.4 1019.0 1121.2 25557.6 26935.5 27328.0 27497.2 27515.0 1054.9 1059.5 1070.3 1152.4 1195.9 14257.3 14290.7 14323.9 14553.3 14618.8 324361.0 327931.0 328632.0 329073.0 329859.0 51018.5 51036.0 51046.4 51278.0 51360.0 1286.9 1308.6 1314.2 1314.6 1337.7 25897.0 27189.5 27329.9 27569.9 28382.0 44070.3 44146.0 44341.1 44376.6 44391.8 225993.0 226799.0 227652.0 227788.0 228173.0 30347.8 31528.5 31898.0 32042.4 32043.8 40584.4 40717.2 41363.4 41675.4 42532.6 34710.0 35183.6 35392.2 35531.6 35888.1 34657.5 35665.6 36148.5 36387.8 36399.0 34896.3 34907.1 35251.5 35339.3 35350.3 31174.8 32348.7 32769.8 32940.8 32966.5 24399.0 24832.6 25478.0 25993.6 26412.7 00:53:05 INFO - 2015-11-21 00:53:05,084 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,084 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29986.52 Median:34767.24 stddev:94502.98 (271.8%) stddev-sans-first:94741.87 00:53:05 INFO - 2015-11-21 00:53:05,084 DEBUG : BROWSER_OUTPUT: Values: 341.7 343.2 344.7 345.3 350.7 16319.0 16624.3 17362.1 17704.0 18384.6 10399.8 11113.8 15915.5 16034.2 16060.8 4565.3 4945.4 5074.4 5098.6 5126.9 4449.5 5070.1 5095.8 5111.5 5761.9 4940.7 5090.3 5105.5 5665.4 5706.4 4975.9 5043.5 5050.4 5055.5 5078.0 5020.4 5074.7 5081.4 5092.9 5103.7 12009.5 12138.6 16059.4 20004.5 20064.2 2009.6 2086.0 2092.2 2146.9 2222.0 8993.7 9440.9 10050.7 10455.0 13095.1 1639.1 1704.5 1719.9 1747.9 1886.7 60313.4 62958.0 64054.0 64091.7 64713.7 10187.6 10315.5 10407.9 10408.0 10419.4 13457.7 13489.9 13517.3 13527.9 13558.9 7450.2 7526.6 7526.8 7560.4 7565.7 7211.8 7223.8 7242.1 7281.8 7292.0 4207.8 4234.9 4401.0 4462.9 4533.5 1841.4 1861.4 1887.0 1904.4 1916.7 396.2 400.2 412.5 420.8 424.2 15864.7 16658.3 17685.6 17864.7 17961.8 923.9 955.4 1011.8 1024.6 1071.9 9342.8 9694.7 9883.4 9906.3 10045.6 565610.0 568669.0 575498.0 590008.0 590374.0 79417.6 80372.0 80743.6 81065.0 82031.6 1156.2 1156.7 1170.2 1179.1 1190.1 9748.5 15572.6 15596.4 15708.2 15758.3 34657.7 34876.8 35912.5 36450.3 36576.0 49332.8 51403.0 52341.2 52617.7 54211.0 13974.9 14343.1 14536.6 14542.9 14924.0 17553.7 17643.6 17670.9 17875.6 18260.4 9351.2 9589.4 9714.9 14501.6 15155.0 7729.0 9496.8 9713.4 9713.9 9777.4 9527.0 9729.1 10026.5 10284.4 10364.1 14747.6 14979.0 15106.9 15121.7 15241.4 14929.6 14984.5 15336.2 15630.4 15996.7 00:53:05 INFO - 2015-11-21 00:53:05,085 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5492.08 Median:6505.85 stddev:2823.08 (43.4%) stddev-sans-first:2814.91 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: Values: 1481.5 1742.0 1771.7 1805.2 1806.5 6833.0 6867.8 7754.5 7969.8 7983.4 7537.6 7600.2 7688.5 7756.0 7771.5 2260.8 2292.6 2314.8 2317.4 2320.7 2296.2 2323.2 2334.5 2337.9 2342.6 2264.0 2265.7 2308.1 2313.0 2315.6 2285.3 2317.3 2328.2 2346.1 2357.8 2340.7 2362.1 2363.3 2364.0 2372.7 7743.3 7851.2 7854.3 7861.8 7880.1 4597.4 4690.3 4700.6 4703.9 4704.1 3205.9 3219.1 3270.3 3288.3 3291.3 1127.3 1130.5 1136.3 1157.9 1165.0 10462.9 10546.7 10589.7 10607.6 10616.6 4240.9 4285.6 4293.4 4345.9 4361.7 9106.3 9153.1 9165.3 9172.4 9178.7 4682.7 4708.3 4723.8 4742.0 4755.6 4242.4 4302.2 4338.1 4354.6 4366.4 3658.8 3674.4 3700.0 3704.7 3725.1 1146.4 1157.2 1162.5 1164.4 1165.5 1351.8 1354.5 1365.8 1372.8 1377.0 7392.2 7506.2 7558.5 7570.9 7572.1 463.0 467.4 468.3 471.5 476.3 5848.7 5889.1 5912.3 5913.7 5922.3 9028.8 9354.3 9449.5 9525.3 9585.2 9333.8 9381.6 9477.7 9477.8 9497.3 7222.4 7319.4 7337.3 7373.7 7428.0 6979.3 7012.9 7050.4 7092.6 7111.6 7981.2 8079.4 8307.8 8317.0 8323.0 8503.4 8508.1 8563.9 8590.1 8596.4 6550.0 6780.4 6955.1 7102.8 7104.0 7596.6 7621.4 7631.1 7642.8 7644.3 7077.6 7211.9 7272.2 7327.1 7377.2 6931.1 6970.9 7080.9 7106.5 7114.9 7022.0 7160.1 7169.0 7181.2 7186.7 6644.3 6678.0 6680.2 6685.2 6696.9 6469.5 6542.1 6559.5 6562.1 6581.0 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5613.97 Median:6423.61 stddev:1733.48 (27.0%) stddev-sans-first:1692.12 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: Values: 316.0 321.0 326.8 327.7 333.4 6272.0 6279.7 6281.5 6305.1 6417.8 6154.6 6307.5 6314.7 6345.3 6367.2 4781.9 4798.0 4800.0 4815.5 4817.0 4827.2 4835.7 4837.4 4838.0 4844.8 4683.1 4747.7 4763.6 4767.5 4780.4 4728.2 4732.9 4750.5 4756.8 4778.2 4135.3 4138.4 4157.9 4162.9 4165.7 5997.4 6021.3 6022.3 6034.4 6035.7 5433.8 5544.3 5569.7 5571.3 5580.6 6630.7 6631.6 6656.4 6671.8 6693.0 4569.3 4570.4 4579.3 4580.3 4583.6 6778.7 6925.3 6929.9 6984.1 6986.0 4558.5 4593.4 4610.3 4613.3 4613.4 4672.3 4672.7 4672.8 4673.9 4682.5 5471.6 5475.1 5480.9 5485.1 5489.1 6621.6 6756.8 6757.2 6758.7 6762.6 1605.6 1612.7 1640.3 1641.4 1645.5 1079.1 1083.3 1094.0 1094.7 1100.5 6435.1 6449.4 6466.6 6474.4 6493.4 6830.8 6883.1 6887.6 6897.2 6897.3 6407.6 6429.4 6475.5 6477.8 6484.8 3796.4 3827.5 3828.8 3832.9 3834.1 7245.5 7284.1 7323.5 7324.6 7341.0 7072.2 7187.5 7215.4 7230.8 7249.4 7305.0 7305.4 7305.6 7307.7 7318.8 6986.8 6997.3 7009.2 7032.3 7042.6 6746.9 6927.2 6954.6 6977.3 6985.1 7254.6 7300.7 7321.1 7324.7 7339.1 4580.9 4630.8 4634.9 4644.4 4647.3 6784.9 6818.6 6943.0 6990.7 6994.2 6798.8 6815.3 6815.8 6823.4 6829.8 6789.6 6790.4 6794.8 6799.7 6806.8 6514.7 6623.9 6662.9 6672.0 6673.7 6827.7 6834.2 6837.4 6838.9 6863.4 6669.9 6674.6 6676.5 6684.1 6686.7 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,086 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13747.17 Median:48171.52 stddev:16120.23 (33.5%) stddev-sans-first:16136.27 00:53:05 INFO - 2015-11-21 00:53:05,087 DEBUG : BROWSER_OUTPUT: Values: 829.4 830.7 832.5 836.1 842.3 12618.6 12918.4 12960.7 15551.2 15754.2 14897.0 14965.6 14969.5 14989.6 15042.5 803.4 806.2 806.8 811.8 813.8 787.7 799.4 801.2 802.5 803.9 811.5 822.4 824.9 826.2 831.2 815.6 825.1 829.5 831.1 831.2 610.5 610.5 610.7 611.4 612.8 12429.8 12446.9 12449.7 12532.7 12537.9 11812.8 11890.3 12234.4 12334.8 12780.0 10002.2 10102.1 10105.9 10339.7 10361.4 3588.2 3589.9 3598.2 3641.0 3710.0 48157.8 48456.8 48816.0 49034.0 49150.3 9407.2 9422.2 9491.8 9550.4 9559.7 17235.2 17409.0 17410.2 17435.7 17462.7 6752.7 6794.8 6865.2 6947.3 7097.1 3933.6 3988.2 4025.5 4051.7 4064.2 3099.1 3109.1 3138.8 3145.0 3151.5 505.0 525.7 532.3 533.2 534.2 524.0 572.9 603.6 612.9 676.6 7636.5 7820.5 8105.4 8134.2 8398.5 554.4 642.4 643.9 676.1 683.1 3523.9 3622.0 3653.6 3678.9 3838.1 56639.7 61399.4 61629.1 61710.4 61876.4 47186.5 47561.0 47566.5 47738.3 48185.3 46002.5 47819.8 48275.8 48468.0 48770.8 7837.4 7994.1 8128.8 8256.5 8529.4 32834.7 33874.3 34204.7 36706.3 36752.8 41692.6 42384.0 42889.6 47043.8 47598.3 9943.0 10757.3 11055.9 11068.7 11521.1 12162.5 12282.8 12303.8 12375.0 12683.9 10547.4 10713.3 10722.8 10903.9 11439.7 11874.3 12160.7 12465.8 12702.7 12759.0 11163.8 11208.9 11249.4 11603.6 11609.2 8945.6 9159.7 9181.9 9221.5 9517.4 8751.2 9035.8 9141.6 9176.9 9461.3 00:53:05 INFO - 2015-11-21 00:53:05,088 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 00:53:05 INFO - 2015-11-21 00:53:05,088 DEBUG : BROWSER_OUTPUT: 00:53:05 INFO - 2015-11-21 00:53:05,706 INFO : Browser exited with error code: 0 00:53:05 INFO - 2015-11-21 00:53:05,724 INFO : Completed test dromaeo_css (00:18:31) 00:53:05 INFO - 2015-11-21 00:53:05,724 INFO : Starting test kraken 00:53:05 INFO - 2015-11-21 00:53:05,724 DEBUG : operating with platform_type : linux_ 00:53:05 INFO - 2015-11-21 00:53:05,724 INFO : Initialising browser for kraken test... 00:53:05 INFO - 2015-11-21 00:53:05,731 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgD1fKu/profile http://localhost:33007/getInfo.html 00:53:10 INFO - 2015-11-21 00:53:10,615 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 00:53:10 INFO - 2015-11-21 00:53:10,615 DEBUG : BROWSER_OUTPUT: colorDepth:24 00:53:10 INFO - 2015-11-21 00:53:10,615 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 00:53:10 INFO - 2015-11-21 00:53:10,616 DEBUG : BROWSER_OUTPUT: __metrics 00:53:11 INFO - 2015-11-21 00:53:11,657 INFO : Browser initialized. 00:53:11 INFO - 2015-11-21 00:53:11,658 INFO : Running cycle 1/1 for kraken test... 00:53:11 INFO - 2015-11-21 00:53:11,658 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgD1fKu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 00:53:23 INFO - 2015-11-21 00:53:23,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 141434880 00:53:23 INFO - 2015-11-21 00:53:23,433 DEBUG : BROWSER_OUTPUT: 00:53:25 INFO - 2015-11-21 00:53:25,912 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33007/tests/kraken/driver.html?testName=audio-beat-detection) 00:53:26 INFO - 2015-11-21 00:53:26,517 DEBUG : BROWSER_OUTPUT: RSS: Main: 141778944 00:53:26 INFO - 2015-11-21 00:53:26,517 DEBUG : BROWSER_OUTPUT: 00:53:29 INFO - 2015-11-21 00:53:29,140 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33007/tests/kraken/driver.html?testName=audio-dft) 00:53:29 INFO - 2015-11-21 00:53:29,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 142180352 00:53:29 INFO - 2015-11-21 00:53:29,741 DEBUG : BROWSER_OUTPUT: 00:53:32 INFO - 2015-11-21 00:53:32,580 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33007/tests/kraken/driver.html?testName=audio-fft) 00:53:33 INFO - 2015-11-21 00:53:33,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 135790592 00:53:33 INFO - 2015-11-21 00:53:33,153 DEBUG : BROWSER_OUTPUT: 00:53:35 INFO - 2015-11-21 00:53:35,413 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33007/tests/kraken/driver.html?testName=audio-oscillator) 00:53:36 INFO - 2015-11-21 00:53:36,034 DEBUG : BROWSER_OUTPUT: RSS: Main: 136892416 00:53:36 INFO - 2015-11-21 00:53:36,035 DEBUG : BROWSER_OUTPUT: 00:53:38 INFO - 2015-11-21 00:53:38,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33007/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:53:38 INFO - 2015-11-21 00:53:38,817 DEBUG : BROWSER_OUTPUT: RSS: Main: 137461760 00:53:38 INFO - 2015-11-21 00:53:38,817 DEBUG : BROWSER_OUTPUT: 00:53:41 INFO - 2015-11-21 00:53:41,816 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33007/tests/kraken/driver.html?testName=imaging-darkroom) 00:53:42 INFO - 2015-11-21 00:53:42,398 DEBUG : BROWSER_OUTPUT: RSS: Main: 138170368 00:53:42 INFO - 2015-11-21 00:53:42,399 DEBUG : BROWSER_OUTPUT: 00:53:45 INFO - 2015-11-21 00:53:45,788 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33007/tests/kraken/driver.html?testName=imaging-desaturate) 00:53:46 INFO - 2015-11-21 00:53:46,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 138612736 00:53:46 INFO - 2015-11-21 00:53:46,377 DEBUG : BROWSER_OUTPUT: 00:53:49 INFO - 2015-11-21 00:53:49,202 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33007/tests/kraken/driver.html?testName=json-parse-financial) 00:53:49 INFO - 2015-11-21 00:53:49,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 138760192 00:53:49 INFO - 2015-11-21 00:53:49,789 DEBUG : BROWSER_OUTPUT: 00:53:51 INFO - 2015-11-21 00:53:51,444 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33007/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:53:52 INFO - 2015-11-21 00:53:52,025 DEBUG : BROWSER_OUTPUT: RSS: Main: 139694080 00:53:52 INFO - 2015-11-21 00:53:52,026 DEBUG : BROWSER_OUTPUT: 00:53:53 INFO - 2015-11-21 00:53:53,876 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:53:54 INFO - 2015-11-21 00:53:54,480 DEBUG : BROWSER_OUTPUT: RSS: Main: 139735040 00:53:54 INFO - 2015-11-21 00:53:54,480 DEBUG : BROWSER_OUTPUT: 00:53:56 INFO - 2015-11-21 00:53:56,620 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:53:57 INFO - 2015-11-21 00:53:57,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 139468800 00:53:57 INFO - 2015-11-21 00:53:57,209 DEBUG : BROWSER_OUTPUT: 00:53:59 INFO - 2015-11-21 00:53:59,696 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:54:00 INFO - 2015-11-21 00:54:00,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 139608064 00:54:00 INFO - 2015-11-21 00:54:00,285 DEBUG : BROWSER_OUTPUT: 00:54:02 INFO - 2015-11-21 00:54:02,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:54:03 INFO - 2015-11-21 00:54:03,465 DEBUG : BROWSER_OUTPUT: RSS: Main: 139972608 00:54:03 INFO - 2015-11-21 00:54:03,465 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33007/tests/kraken/driver.html?testName=ai-astar) 00:54:05 INFO - 2015-11-21 00:54:05,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 138825728 00:54:05 INFO - 2015-11-21 00:54:05,418 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,420 DEBUG : BROWSER_OUTPUT: __start_tp_report 00:54:05 INFO - 2015-11-21 00:54:05,420 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 00:54:05 INFO - 2015-11-21 00:54:05,420 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 00:54:05 INFO - 2015-11-21 00:54:05,420 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 00:54:05 INFO - 2015-11-21 00:54:05,420 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;104;110;110;111;122;111;111;110;110 00:54:05 INFO - 2015-11-21 00:54:05,421 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;134;153;163;125;154;159;126;160;126 00:54:05 INFO - 2015-11-21 00:54:05,421 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;163;163;163;163;160;162;166;173;163 00:54:05 INFO - 2015-11-21 00:54:05,421 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;72;72;73;72;136;73;73;73 00:54:05 INFO - 2015-11-21 00:54:05,421 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;134;89;91;91;133;88;90;139 00:54:05 INFO - 2015-11-21 00:54:05,421 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;110;110;110;110;114 00:54:05 INFO - 2015-11-21 00:54:05,422 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;147;147;147;147;148;146;146;147 00:54:05 INFO - 2015-11-21 00:54:05,422 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;97;96;95;96;96;96 00:54:05 INFO - 2015-11-21 00:54:05,422 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;67;68;67;67;67;69;67;70;65;68 00:54:05 INFO - 2015-11-21 00:54:05,422 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;69;70;69;70;70;69;70 00:54:05 INFO - 2015-11-21 00:54:05,422 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;91;85;93;85;86;85;86;93;95;87 00:54:05 INFO - 2015-11-21 00:54:05,423 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;125;133;113;122;128;120;133;123;125 00:54:05 INFO - 2015-11-21 00:54:05,423 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;152;155;150;151;140;145;153;155;151 00:54:05 INFO - 2015-11-21 00:54:05,423 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;69;72;70;68;71;71;72;70;70 00:54:05 INFO - 2015-11-21 00:54:05,423 DEBUG : BROWSER_OUTPUT: __end_tp_report 00:54:05 INFO - 2015-11-21 00:54:05,423 DEBUG : BROWSER_OUTPUT: __start_cc_report 00:54:05 INFO - 2015-11-21 00:54:05,424 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,696 00:54:05 INFO - 2015-11-21 00:54:05,424 DEBUG : BROWSER_OUTPUT: __end_cc_report 00:54:05 INFO - 2015-11-21 00:54:05,424 DEBUG : BROWSER_OUTPUT: __startTimestamp1448096045418__endTimestamp 00:54:05 INFO - 2015-11-21 00:54:05,424 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: Number of tests: 14 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:4.44 (4.0%) stddev-sans-first:4.66 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: Values: 113.0 104.0 110.0 110.0 111.0 122.0 111.0 111.0 110.0 110.0 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,426 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.00 Median:153.50 stddev:15.48 (10.1%) stddev-sans-first:16.32 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: Values: 150.0 134.0 153.0 163.0 125.0 154.0 159.0 126.0 160.0 126.0 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:3.55 (2.2%) stddev-sans-first:3.71 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: Values: 162.0 163.0 163.0 163.0 163.0 160.0 162.0 166.0 173.0 163.0 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.80 Median:72.50 stddev:20.10 (27.7%) stddev-sans-first:21.17 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 72.0 72.0 73.0 72.0 136.0 73.0 73.0 73.0 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,427 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.40 Median:89.50 stddev:22.11 (24.7%) stddev-sans-first:22.83 00:54:05 INFO - 2015-11-21 00:54:05,428 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 134.0 89.0 91.0 91.0 133.0 88.0 90.0 139.0 00:54:05 INFO - 2015-11-21 00:54:05,428 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,428 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.40 Median:110.00 stddev:1.26 (1.1%) stddev-sans-first:1.33 00:54:05 INFO - 2015-11-21 00:54:05,428 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 114.0 00:54:05 INFO - 2015-11-21 00:54:05,428 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 147.0 147.0 147.0 147.0 148.0 146.0 146.0 147.0 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 97.0 96.0 95.0 96.0 96.0 96.0 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:67.50 Median:67.50 stddev:1.35 (2.0%) stddev-sans-first:1.42 00:54:05 INFO - 2015-11-21 00:54:05,429 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 67.0 67.0 67.0 69.0 67.0 70.0 65.0 68.0 00:54:05 INFO - 2015-11-21 00:54:05,430 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,430 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:0.79 (1.1%) stddev-sans-first:0.71 00:54:05 INFO - 2015-11-21 00:54:05,430 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 69.0 70.0 69.0 70.0 70.0 69.0 70.0 00:54:05 INFO - 2015-11-21 00:54:05,430 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,431 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:89.00 stddev:3.95 (4.4%) stddev-sans-first:4.09 00:54:05 INFO - 2015-11-21 00:54:05,431 DEBUG : BROWSER_OUTPUT: Values: 91.0 85.0 93.0 85.0 86.0 85.0 86.0 93.0 95.0 87.0 00:54:05 INFO - 2015-11-21 00:54:05,431 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,431 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:125.00 stddev:6.42 (5.1%) stddev-sans-first:6.30 00:54:05 INFO - 2015-11-21 00:54:05,431 DEBUG : BROWSER_OUTPUT: Values: 117.0 125.0 133.0 113.0 122.0 128.0 120.0 133.0 123.0 125.0 00:54:05 INFO - 2015-11-21 00:54:05,431 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.90 Median:151.50 stddev:4.70 (3.1%) stddev-sans-first:4.87 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: Values: 147.0 152.0 155.0 150.0 151.0 140.0 145.0 153.0 155.0 151.0 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.50 stddev:1.32 (1.9%) stddev-sans-first:1.32 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 72.0 70.0 68.0 71.0 71.0 72.0 70.0 70.0 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 00:54:05 INFO - 2015-11-21 00:54:05,432 DEBUG : BROWSER_OUTPUT: 00:54:06 INFO - 2015-11-21 00:54:06,365 INFO : Browser exited with error code: 0 00:54:06 INFO - 2015-11-21 00:54:06,419 INFO : Completed test kraken (00:01:00) 00:54:06 INFO - 2015-11-21 00:54:06,419 INFO : Starting test v8_7 00:54:06 INFO - 2015-11-21 00:54:06,419 DEBUG : operating with platform_type : linux_ 00:54:06 INFO - 2015-11-21 00:54:06,419 INFO : Initialising browser for v8_7 test... 00:54:06 INFO - 2015-11-21 00:54:06,425 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmg9W15/profile http://localhost:33007/getInfo.html 00:54:11 INFO - 2015-11-21 00:54:11,044 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 00:54:11 INFO - 2015-11-21 00:54:11,044 DEBUG : BROWSER_OUTPUT: colorDepth:24 00:54:11 INFO - 2015-11-21 00:54:11,044 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 00:54:11 INFO - 2015-11-21 00:54:11,044 DEBUG : BROWSER_OUTPUT: __metrics 00:54:12 INFO - 2015-11-21 00:54:12,157 INFO : Browser initialized. 00:54:12 INFO - 2015-11-21 00:54:12,158 INFO : Running cycle 1/1 for v8_7 test... 00:54:12 INFO - 2015-11-21 00:54:12,158 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmg9W15/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 00:54:23 INFO - 2015-11-21 00:54:23,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 142118912 00:54:23 INFO - 2015-11-21 00:54:23,959 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33007/tests/v8_7/run.html (next: http://localhost:33007/tests/v8_7/run.html) 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 139268096 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: __start_tp_report 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |0;Richards;163.63933889707084 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.08746862328636 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.68794326241135 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5050.50505050505 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1055.9662090813094 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |5;Earley;695.8942240779402 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |6;Boyer;12023.809523809523 00:54:45 INFO - 2015-11-21 00:54:45,608 DEBUG : BROWSER_OUTPUT: |7;RegExp;25820.51282051282 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: |8;Splay;593.1401537551744 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: __end_tp_report 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: __startTimestamp1448096085601__endTimestamp 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: Number of tests: 10 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.64 Median:163.64 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: Values: 163.6 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.09 Median:193.09 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: Values: 193.1 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,609 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.69 Median:283.69 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: Values: 283.7 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5050.51 Median:5050.51 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: Values: 5050.5 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1055.97 Median:1055.97 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: Values: 1056.0 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:695.89 Median:695.89 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: Values: 695.9 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12023.81 Median:12023.81 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: Values: 12023.8 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25820.51 Median:25820.51 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,610 DEBUG : BROWSER_OUTPUT: Values: 25820.5 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.14 Median:593.14 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: Values: 593.1 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: Values: 4840.6 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 00:54:45 INFO - 2015-11-21 00:54:45,611 DEBUG : BROWSER_OUTPUT: 00:54:46 INFO - 2015-11-21 00:54:46,481 INFO : Browser exited with error code: 0 00:54:46 INFO - 2015-11-21 00:54:46,496 INFO : Completed test v8_7 (00:00:40) 00:54:46 INFO - 2015-11-21 00:54:46,613 INFO : Completed test suite (00:20:12) 00:54:46 INFO - 2015-11-21 00:54:46,613 DEBUG : Working with test: dromaeo_css 00:54:46 INFO - 2015-11-21 00:54:46,613 DEBUG : Generating results file: dromaeo_css 00:54:46 INFO - 2015-11-21 00:54:46,614 DEBUG : Working with test: kraken 00:54:46 INFO - 2015-11-21 00:54:46,615 DEBUG : Generating results file: kraken 00:54:46 INFO - 2015-11-21 00:54:46,615 INFO : javascript benchmark 00:54:46 INFO - 2015-11-21 00:54:46,615 DEBUG : Working with test: v8_7 00:54:46 INFO - 2015-11-21 00:54:46,615 DEBUG : Generating results file: v8_7 00:54:46 INFO - 2015-11-21 00:54:46,615 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 00:54:46 INFO - 2015-11-21 00:54:46,674 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 00:54:46 INFO - 2015-11-21 00:54:46,675 DEBUG : process_Request line: dromaeo_css 7014.88 graph.html#tests=[[72,63,43]] 00:54:46 INFO - 2015-11-21 00:54:46,675 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 00:54:46 INFO - 2015-11-21 00:54:46,794 DEBUG : process_Request line: kraken 1524.80 graph.html#tests=[[232,63,43]] 00:54:46 INFO - 2015-11-21 00:54:46,794 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 00:54:46 INFO - 2015-11-21 00:54:46,832 DEBUG : process_Request line: v8_7 21490.73 graph.html#tests=[[230,63,43]] 00:54:46 INFO - 2015-11-21 00:54:46,833 INFO : javascript benchmark 00:54:46 INFO - 2015-11-21 00:54:46,837 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3231.8384080795963, 3294.9950265169127, 3315.552400425942, 3333.4333163362226, 3344.782589131706, 5305.946940530594, 5503.394626591072, 5533.972330138348, 5543.501084902363, 5552.555795536357, 5446.373667028283, 5457.181422786584, 5486.396496385394, 5506.449355064491, 5552.77788888444, 4617.2381557043045, 4923.040007198598, 5095.414027386861, 5108.948910510884, 5115.258287548303, 4894.632902532307, 5052.469490703478, 5091.261767043777, 5114.28400023997, 5142.228665700138, 4917.680350777188, 4986.052649996525, 5011.0, 5034.798608055673, 5035.345405097332, 5024.773885175176, 5079.771410286546, 5084.339035925343, 5113.130767769489, 5145.253938178943, 4967.801287948478, 5022.029944775612, 5041.949580504184, 5059.974700126476, 5090.618203634705, 5246.501582349671, 5248.895022099537, 5261.184516399964, 5284.0, 5297.0, 5870.794522191745, 5872.383399743008, 5875.471207591295, 5877.970610146922, 5887.6173048752125, 5830.096335068071, 5864.120381942743, 5880.882382352372, 5894.528437724972, 5901.3508514063415, 5585.0, 5646.632968857011, 5654.5193658538665, 5656.91514627281, 5706.400827913092, 6001.639901605918, 6004.249468816398, 6089.5737298388685, 6091.786787462417, 6094.9695251524345, 5742.196092547047, 5808.36108028125, 5813.6221145625395, 5890.2342695449315, 5917.55618328627, 5976.85057873548, 6004.129401236796, 6017.308009578867, 6031.125486804476, 6045.063015232646, 5983.222181116427, 5988.071848863346, 5991.7902873399225, 5995.070764031582, 5996.850078747979, 5777.653340799566, 5849.53203743708, 5898.056310990221, 5899.823005309848, 5986.610870293417, 4533.0, 4559.361689363492, 4570.862874113782, 4588.036512332381, 4618.422697162855, 4088.386741988666, 4091.263572556909, 4092.0, 4103.7742924138865, 4130.235906357333, 5683.857903552362, 5702.030654788696, 5708.9714551427805, 5723.77104915807, 5776.249087618583, 5808.099744539603, 5897.144913987448, 5901.232839730807, 5972.761089556456, 6025.0359942409, 5652.491275785199, 5679.971600142056, 5682.772689092472, 5695.45893140151, 5706.543476521868, 5115.948840511622, 5165.61257905651, 5211.348581427322, 5222.00781851447, 5272.20916862466, 5998.0, 6009.038553831453, 6013.037913933749, 6014.308354539196, 6028.909566356509, 5928.79249226275, 5994.67029313392, 5999.5800293979, 6006.579539432372, 6049.062395328731, 6007.918574656604, 6102.4507794298725, 6131.570790044654, 6149.569530132848, 6199.690015499297, 5631.718414079198, 5682.147677848356, 5747.7413516391025, 5890.381509941394, 5901.409859014236, 5828.184054232495, 5853.414658534283, 5868.4424979626865, 5874.735636896265, 5931.3772053935445, 5857.619254748428, 5860.619059761102, 5872.0898260769645, 5942.554308426799, 5975.312839023568, 5822.243108395882, 5849.093390524476, 5854.180414742024, 5857.941420585739, 5878.764849405976, 5665.178549110438, 5741.483266506035, 5750.367459579526, 5779.393163717918, 5808.390119037609, 5822.475977162076, 5849.795257165979, 5860.531157507304, 5866.677332746569, 5873.765049398147, 5706.087026075808, 5732.71336433185, 5735.197072409783, 5735.4551317624755, 5742.655440673573], "unit": "ms", "name": "dojo.html", "value": 5473.498238721661}, {"lowerIsBetter": false, "replicates": [554.8919483560256, 589.3899813692829, 590.7695998560561, 603.9818805435839, 608.0241212853373, 38744.41883371748, 39740.40389394154, 39849.40225896614, 39981.20037599246, 40005.39991900124, 41873.790631046875, 42173.57826421735, 42491.78754106225, 42506.36240456396, 42524.787376063156, 8719.389642725027, 8845.4250473719, 8845.955770221171, 8857.867131993025, 8857.911420885774, 8659.740207793777, 8734.694285700001, 8767.254783343424, 8770.386072974863, 8805.20753132218, 8697.086805885387, 8768.60541275641, 8785.989611194698, 8787.165219304188, 8788.384813063089, 8679.0, 8732.738017859474, 8756.562171891444, 8769.0, 8770.736877893672, 8517.74446766598, 8647.827043459158, 8650.783730406727, 8665.740027799176, 8704.434211776213, 39913.201735965406, 39939.8003009986, 40215.0, 40456.39315410271, 40468.0, 3152.921176970571, 3164.9841750791097, 3226.3063441359986, 3328.517364982064, 3512.3150985557827, 28563.85718071417, 29214.41571168586, 29673.851630741923, 29803.70196298031, 29810.70189298101, 3774.9433758493647, 3812.1613245086037, 3864.768113913174, 3892.2020985698146, 4009.719319647626, 273837.0, 275079.0, 275106.0, 275571.0, 276513.0, 49742.27448392397, 50558.24162637563, 56519.0, 57390.0, 57419.42580574224, 53524.464755352725, 53650.19524707132, 53862.922741545735, 53864.19203711947, 53927.0, 14882.702345953021, 14994.850051499345, 15038.17290049036, 15049.623759406102, 15100.0, 11315.660530184108, 11385.861413858547, 11404.429778511041, 11476.311421314747, 11567.652970410902, 18844.905775471034, 18870.0, 19053.42839714811, 19130.904345478182, 19182.712259316122, 7900.076971520574, 8036.8748375227515, 8038.799030024296, 8075.798105047421, 8157.796055098552, 981.1365997921783, 981.4111533080237, 1004.4425343934041, 1019.0166489337824, 1121.243160866412, 25557.616635750477, 26935.461290774074, 27328.0, 27497.17508474749, 27515.0, 1054.9103326217205, 1059.51262419288, 1070.29895418501, 1152.4295473740385, 1195.8564972203392, 14257.287135643177, 14290.714185716435, 14323.92838035824, 14553.345099470549, 14618.780718289234, 324361.0, 327931.0, 328632.0, 329073.0, 329859.0, 51018.48981510212, 51036.0, 51046.405823475325, 51278.0, 51360.0, 1286.9163504372373, 1308.5681725030718, 1314.2311747627555, 1314.605618314521, 1337.6856438736888, 25897.0, 27189.456210875276, 27329.86335068312, 27569.862150689118, 28382.0, 44070.33894491649, 44146.0, 44341.11317773562, 44376.556234437245, 44391.77804110959, 225993.0, 226799.0, 227652.0, 227788.0, 228173.0, 30347.848260758554, 31528.527072094395, 31898.0, 32042.359152816345, 32043.839780800947, 40584.39123413092, 40717.1856562873, 41363.379549307385, 41675.37486937759, 42532.574674252864, 34710.0, 35183.64816351804, 35392.1519620095, 35531.64468355283, 35888.10279742923, 34657.480137798455, 35665.64334356727, 36148.4577731329, 36387.81806090953, 36399.0, 34896.30207395888, 34907.127321817155, 35251.47122793212, 35339.29321413609, 35350.29299413946, 31174.844125779226, 32348.676513234568, 32769.83615082005, 32940.835295823366, 32966.505502417, 24399.0, 24832.627510586994, 25478.0, 25993.6100958482, 26412.735872641028], "unit": "ms", "name": "ext.html", "value": 18139.590550483037}, {"lowerIsBetter": false, "replicates": [341.6839423533231, 343.20376725995686, 344.6501800672317, 345.25080575151935, 350.726433381962, 16319.020858748469, 16624.335026598936, 17362.131893405327, 17704.0, 18384.632307353844, 10399.792004159912, 11113.777724445506, 15915.52253432396, 16034.23623401425, 16060.839391606081, 4565.315202719594, 4945.4065512138595, 5074.416442109168, 5098.56662183713, 5126.948730512684, 4449.4660640723, 5070.112730272206, 5095.770690318945, 5111.514406131411, 5761.884762304751, 4940.678855874375, 5090.287359769635, 5105.489451054883, 5665.3768085510765, 5706.372299047122, 4975.875603109915, 5043.470435604264, 5050.419201791804, 5055.494450554934, 5078.035173317076, 5020.447750747451, 5074.746262686851, 5081.415637201695, 5092.872678183038, 5103.668261563023, 12009.519619215222, 12138.635840924786, 16059.357625695075, 20004.49988750293, 20064.197432102843, 2009.5578972626, 2085.9687104693444, 2092.1840482211796, 2146.8604540704805, 2221.955560888773, 8993.730188094367, 9440.858387124199, 10050.748731281703, 10455.0, 13095.083344165912, 1639.1148779659088, 1704.5142134491734, 1719.8796084273981, 1747.877648564601, 1886.6604011277964, 60313.39686603122, 62957.99999999954, 64054.0, 64091.67954160246, 64713.67643161801, 10187.592496300065, 10315.484225788681, 10407.94796026015, 10408.0, 10419.42693151884, 13457.663558410923, 13489.932550337186, 13517.256550889599, 13527.864721352857, 13558.93220533891, 7450.217727138681, 7526.5860377679755, 7526.811829704301, 7560.3573696236435, 7565.6973721050545, 7211.783646490614, 7223.783286501413, 7242.058532390755, 7281.817954551178, 7291.963540182265, 4207.8316867325575, 4234.872953811329, 4400.9559904401185, 4462.933056004163, 4533.523979982055, 1841.3555255660413, 1861.376438892986, 1887.0467718021252, 1904.3620387170176, 1916.7124931260423, 396.23724330663356, 400.1676512853258, 412.51117425850464, 420.7959139817186, 424.1982652786175, 15864.682706345808, 16658.250378732988, 17685.646287074185, 17864.732029019575, 17961.820381796275, 923.8845144356956, 955.441066975827, 1011.7723512209841, 1024.6157690865925, 1071.908887744535, 9342.766430839283, 9694.660686875926, 9883.369244074791, 9906.306558540977, 10045.648402305884, 565610.0, 568669.0, 575498.0, 590008.0, 590374.0, 79417.60291198622, 80372.0, 80743.59628201822, 81065.0, 82031.58984205041, 1156.1848896528095, 1156.653004098767, 1170.2101081769772, 1179.0508640544272, 1190.125257935434, 9748.51257437111, 15572.610684732972, 15596.440355964312, 15708.21458927072, 15758.290876910338, 34657.65342346544, 34876.825615871756, 35912.4613130798, 36450.27099457943, 36576.0, 49332.75333623309, 51402.97194056173, 52341.21488177757, 52617.7369113152, 54211.0, 13974.860251397762, 14343.139411635335, 14536.563903082924, 14542.909281803695, 14924.0, 17553.736693949857, 17643.55891102733, 17670.91164544169, 17875.553111172325, 18260.36088736888, 9351.205147562534, 9589.376690515093, 9714.902850971683, 14501.564953051426, 15155.0, 7729.0, 9496.78339548653, 9713.417194968324, 9713.882903466192, 9777.41335519871, 9526.952365238129, 9729.075737804897, 10026.548805303633, 10284.382937023802, 10364.119049880845, 14747.55757327282, 14979.0, 15106.866984975952, 15121.697566048837, 15241.390344386544, 14929.552113436614, 14984.452643050925, 15336.156511391535, 15630.374785008473, 15996.68006639884], "unit": "ms", "name": "jquery.html", "value": 8608.277530298066}, {"lowerIsBetter": false, "replicates": [1481.511101336559, 1741.9738703919443, 1771.6633839570482, 1805.2057094878253, 1806.4761219246416, 6832.965835170818, 6867.793966181022, 7754.495957762741, 7969.840603187933, 7983.401244906633, 7537.62311884405, 7600.1639819619795, 7688.500247483909, 7755.961220193905, 7771.455998080137, 2260.7852254035884, 2292.5529521743265, 2314.7916687498123, 2317.3627252505526, 2320.7447180810095, 2296.150424342987, 2323.186884590397, 2334.5447637710654, 2337.91817286395, 2342.5666251743482, 2264.0377839418265, 2265.6714776357417, 2308.1113771198125, 2313.0, 2315.5716192504356, 2285.3029825903095, 2317.2932255662017, 2328.196772113618, 2346.085026839525, 2357.823163262745, 2340.6840076589783, 2362.14962613459, 2363.3264519612, 2364.0189321431585, 2372.7034120734907, 7743.264389883008, 7851.175626559186, 7854.293113619745, 7861.842763144705, 7880.0937892142565, 4597.356370108188, 4690.273007683814, 4700.647451441121, 4703.929441058387, 4704.1062198182235, 3205.9198520036953, 3219.098652377338, 3270.2641905571295, 3288.3094550144497, 3291.308825146722, 1127.3405058041055, 1130.4856290387854, 1136.3409222650844, 1157.9478923448464, 1164.9825252621217, 10462.895371046268, 10546.683599492027, 10589.682309530726, 10607.628732994308, 10616.62841800541, 4240.936385954213, 4285.6142947134285, 4293.355996600472, 4345.891797591594, 4361.672874534423, 9106.31702622306, 9153.130452606993, 9165.31260155491, 9172.44965302084, 9178.67874624385, 4682.719036857799, 4708.270218116198, 4723.7874295656775, 4742.0, 4755.643326750438, 4242.363645453207, 4302.182585308781, 4338.067315527113, 4354.586314300136, 4366.43236379275, 3658.835352409148, 3674.393725035393, 3700.0, 3704.7406681532575, 3725.0501122213664, 1146.432515904616, 1157.2188772578645, 1162.4768854015726, 1164.4119719541748, 1165.5337864854125, 1351.770199066161, 1354.4582167133028, 1365.8156148919968, 1372.7529044772036, 1376.951806686761, 7392.186859445456, 7506.249375062559, 7558.546487210675, 7570.886436703454, 7572.053493313336, 463.04612498253704, 467.38538821449686, 468.3489948970933, 471.4931448692669, 476.2713049034982, 5848.736806843703, 5889.087191485327, 5912.260967379078, 5913.674747888821, 5922.2597175353085, 9028.774280643036, 9354.251659867194, 9449.480278584606, 9525.333226674065, 9585.185259253041, 9333.813323733622, 9381.62473501066, 9477.66828161025, 9477.810443791224, 9497.335186536877, 7222.422206223489, 7319.377852882458, 7337.339639432476, 7373.705051797975, 7428.034355533854, 6979.267176946348, 7012.859742805013, 7050.400715939202, 7092.574445533284, 7111.573305601681, 7981.241782030698, 8079.434439589172, 8307.792305192419, 8317.043539992977, 8323.001239851257, 8503.404761666625, 8508.149185081442, 8563.914360856561, 8590.14098590136, 8596.35527335465, 6549.967250163719, 6780.423663988616, 6955.061066756126, 7102.751403700846, 7104.040954471287, 7596.582187979714, 7621.428392870446, 7631.122420921665, 7642.8089297768, 7644.273793989562, 7077.6461176941975, 7211.927880721126, 7272.23641517633, 7327.120745510573, 7377.151627562899, 6931.133608298963, 6970.895436568557, 7080.929190708027, 7106.502544821813, 7114.893276600752, 7021.964890175516, 7160.10498687664, 7168.964155179191, 7181.24596917337, 7186.748463803742, 6644.269130395749, 6678.0, 6680.164979377578, 6685.164354455693, 6696.933030669631, 6469.547131700736, 6542.149520562297, 6559.508036897348, 6562.081308616893, 6581.045748366364], "unit": "ms", "name": "mootools.html", "value": 4468.199535502642}, {"lowerIsBetter": false, "replicates": [316.0423915535927, 320.9695078967498, 326.8431153046537, 327.7279857718094, 333.3965522404448, 6272.027835685476, 6279.686015699214, 6281.466075383592, 6305.085762564426, 6417.839554011152, 6154.599951003182, 6307.526935479835, 6314.715837787299, 6345.270293916201, 6367.235931688192, 4781.90436191276, 4797.99242159147, 4799.952000479985, 4815.494373090827, 4817.0, 4827.179379505475, 4835.709857408567, 4837.371141751567, 4838.0, 4844.781984810692, 4683.1336202802595, 4747.691400059002, 4763.595094416962, 4767.547083027124, 4780.350778981263, 4728.243481043017, 4732.858014259577, 4750.453697824725, 4756.762161891909, 4778.235482322812, 4135.276326642856, 4138.420621113047, 4157.896052598679, 4162.895927601804, 4165.729227600226, 5997.400259973968, 6021.307549631761, 6022.337542870325, 6034.396560343975, 6035.7283922223605, 5433.8369848904595, 5544.306961629796, 5569.693666848321, 5571.275734154575, 5580.553555715533, 6630.73477060917, 6631.568948018364, 6656.36764507371, 6671.799846004627, 6692.966535167342, 4569.268916973301, 4570.428696412949, 4579.290210017452, 4580.267157254823, 4583.610393116589, 6778.661066946683, 6925.272846351111, 6929.896051559231, 6984.126984126984, 6986.021956925983, 4558.4529856416775, 4593.356930029799, 4610.3084537319, 4613.261878099555, 4613.377194078824, 4672.322513235562, 4672.719636821802, 4672.813087476531, 4673.929891051637, 4682.484926658065, 5471.6169868108855, 5475.09660905954, 5480.862978425571, 5485.067538518461, 5489.1217405215775, 6621.635810030494, 6756.763513277012, 6757.155355580552, 6758.662066896635, 6762.594244345355, 1605.6307049378474, 1612.6532795449034, 1640.2946732904727, 1641.3762770147507, 1645.4816732729153, 1079.0881704959297, 1083.2525557365393, 1093.9890601094046, 1094.7263184203948, 1100.526773487408, 6435.131257280301, 6449.355064493513, 6466.612003279818, 6474.449671777952, 6493.448056915121, 6830.829229269209, 6883.105196324546, 6887.586744795228, 6897.172339319213, 6897.310268973062, 6407.583507072025, 6429.38920802523, 6475.546711730228, 6477.805665830033, 6484.805455836333, 3796.3735983562956, 3827.483289755903, 3828.8085595720104, 3832.885013449545, 3834.118152824866, 7245.529040612343, 7284.125904891447, 7323.450741194431, 7324.560526368435, 7341.008963789926, 7072.222055573883, 7187.496875218684, 7215.386692131227, 7230.819229519199, 7249.420046396275, 7304.96347518259, 7305.41556675454, 7305.561666300039, 7307.744228951961, 7318.780436586911, 6986.825329366603, 6997.300269972962, 7009.228984811769, 7032.331928466788, 7042.647867606702, 6746.8650626988165, 6927.203371612329, 6954.617496037564, 6977.2673869243, 6985.057017302803, 7254.600996945219, 7300.707971681073, 7321.121465424183, 7324.707011719472, 7339.082614673166, 4580.908381832278, 4630.768461576975, 4634.907301854011, 4644.419447569054, 4647.279671650899, 6784.93215067843, 6818.624975626388, 6942.965285173744, 6990.685419156252, 6994.160700715946, 6798.76204332846, 6815.284395138439, 6815.829604259933, 6823.385895269251, 6829.829254268683, 6789.558678685869, 6790.354916282945, 6794.796156115324, 6799.694013769491, 6806.7957961261245, 6514.674266286762, 6623.933760662524, 6662.866742665023, 6672.0, 6673.699683514156, 6827.726890924308, 6834.2482326943, 6837.384635382839, 6838.897416538656, 6863.38229559342, 6669.866602668017, 6674.566153200027, 6676.499262555425, 6684.131062961784, 6686.732530698912], "unit": "ms", "name": "prototype.html", "value": 5034.985935976799}, {"lowerIsBetter": false, "replicates": [829.4442723375338, 830.6843399508186, 832.5129799067546, 836.0886633569409, 842.3429724814639, 12618.62144135675, 12918.418671159809, 12960.675983100427, 15551.22243887804, 15754.212289385543, 14897.0, 14965.625859353522, 14969.47606833761, 14989.625259368495, 15042.548723538284, 803.4135081390589, 806.238104990784, 806.7620052084649, 811.7889348769304, 813.8128230506987, 787.7203592724586, 799.4124318625805, 801.1667865419956, 802.5144787403596, 803.8593246181924, 811.5374236685092, 822.3667775812621, 824.9216324449197, 826.2150956591232, 831.222806675757, 815.6166601697222, 825.1088824070019, 829.531314807132, 831.1356189562907, 831.2477208126655, 610.4566935427473, 610.548194336188, 610.7282259394572, 611.3672349118641, 612.8498517863152, 12429.813552796715, 12446.937765311206, 12449.688757781129, 12532.686682833002, 12537.93731031339, 11812.822807657893, 11890.346030968294, 12234.449449774782, 12334.81497777534, 12780.04149688777, 10002.199824014062, 10102.14131798798, 10105.9494702526, 10339.689809305733, 10361.37831730091, 3588.1747198144312, 3589.9102522437147, 3598.208394153282, 3641.035125691694, 3710.0353907984118, 48157.75921120372, 48456.75771621155, 48816.0, 49034.0, 49150.26274605884, 9407.247420206368, 9422.152006319327, 9491.810163796685, 9550.426974381558, 9559.665411710557, 17235.22441490136, 17408.955462672282, 17410.21654025572, 17435.73846392305, 17462.650746985244, 6752.696128674222, 6794.796156115324, 6865.176178858569, 6947.270536593584, 7097.077379940678, 3933.645971862546, 3988.1824226033596, 4025.5370632377067, 4051.716379853382, 4064.207479541461, 3099.070278916316, 3109.129443755752, 3138.7959782614057, 3145.0, 3151.5430262611794, 504.9798008079709, 525.6635753118007, 532.2761044978826, 533.1922137960992, 534.2253732088487, 523.9554637855825, 572.9169270455844, 603.6167033933484, 612.8927437698385, 676.6143298320009, 7636.465447418738, 7820.452568320276, 8105.351571874236, 8134.186581341937, 8398.454100483448, 554.3936691819687, 642.4218203616689, 643.9195100612424, 676.1345477788439, 683.111954459201, 3523.9295214096087, 3621.9818900905325, 3653.5514816349164, 3678.9448158277646, 3838.059675379494, 56639.71680141573, 61399.386006139364, 61629.07556386658, 61710.38289617136, 61876.38123618706, 47186.5281347189, 47561.0, 47566.52433475621, 47738.28392574114, 48185.277220841715, 46002.53997459982, 47819.760901195965, 48275.75862120667, 48468.0, 48770.75614621975, 7837.37301015906, 7994.080680721559, 8128.75613731589, 8256.504609723435, 8529.360297977553, 32834.67165328412, 33874.3225135491, 34204.657953420145, 36706.26587468289, 36752.816235918646, 41692.58307416887, 42384.0, 42889.57110428856, 47043.76478117587, 47598.28602570895, 9942.950285248528, 10757.30077544957, 11055.89441055888, 11068.667939961813, 11521.078313735048, 12162.452689629165, 12282.754344912873, 12303.815442768187, 12375.0, 12683.87316126827, 10547.367157970546, 10713.303635263683, 10722.839157412802, 10903.890961090605, 11439.714007149887, 11874.287542747463, 12160.695982600151, 12465.813012804634, 12702.745945081231, 12759.0, 11163.832542512031, 11208.887911120784, 11249.381284029458, 11603.558371437543, 11609.165706109306, 8945.552722363725, 9159.725208243763, 9181.862272066059, 9221.538923053686, 9517.428954262767, 8751.168638979287, 9035.759739713081, 9141.63433462654, 9176.862347064933, 9461.290403219924], "unit": "ms", "name": "yui.html", "value": 6196.465935882557}], "name": "dromaeo_css", "value": 7014.874448172459}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 104.0, 110.0, 110.0, 111.0, 122.0, 111.0, 111.0, 110.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [150.0, 134.0, 153.0, 163.0, 125.0, 154.0, 159.0, 126.0, 160.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.0}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 163.0, 163.0, 163.0, 160.0, 162.0, 166.0, 173.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 163.8}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 72.0, 72.0, 73.0, 72.0, 136.0, 73.0, 73.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 78.8}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 134.0, 89.0, 91.0, 91.0, 133.0, 88.0, 90.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 103.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 114.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.4}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 146.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 95.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 67.0, 67.0, 69.0, 67.0, 70.0, 65.0, 68.0], "unit": "ms", "name": "json-parse-financial", "value": 67.5}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 69.0, 70.0, 69.0, 70.0, 70.0, 69.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [91.0, 85.0, 93.0, 85.0, 86.0, 85.0, 86.0, 93.0, 95.0, 87.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 88.6}, {"lowerIsBetter": true, "replicates": [117.0, 125.0, 133.0, 113.0, 122.0, 128.0, 120.0, 133.0, 123.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [147.0, 152.0, 155.0, 150.0, 151.0, 140.0, 145.0, 153.0, 155.0, 151.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.9}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 72.0, 70.0, 68.0, 71.0, 71.0, 72.0, 70.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.2}], "name": "kraken", "value": 1524.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.63933889707084], "unit": "score", "name": "Richards", "value": 215.73052199999998}, {"lowerIsBetter": false, "replicates": [193.08746862328636], "unit": "score", "name": "DeltaBlue", "value": 342.42512200000004}, {"lowerIsBetter": false, "replicates": [283.68794326241135], "unit": "score", "name": "Encrypt", "value": 938.2880249999996}, {"lowerIsBetter": false, "replicates": [5050.50505050505], "unit": "score", "name": "Decrypt", "value": 52.70383799999996}, {"lowerIsBetter": false, "replicates": [1055.9662090813094], "unit": "score", "name": "RayTrace", "value": 700.7695829999997}, {"lowerIsBetter": false, "replicates": [695.8942240779402], "unit": "score", "name": "Earley", "value": 957.7073310000004}, {"lowerIsBetter": false, "replicates": [12023.809523809523], "unit": "score", "name": "Boyer", "value": 55.42860594059401}, {"lowerIsBetter": false, "replicates": [25820.51282051282], "unit": "score", "name": "RegExp", "value": 35.28144488579943}, {"lowerIsBetter": false, "replicates": [593.1401537551744], "unit": "score", "name": "Splay", "value": 137.389113659023}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21490.731078830973}]} 00:54:46 INFO - RETURN: dromaeo_css: 7014.88 00:54:46 INFO - RETURN: kraken: 1524.80 00:54:46 INFO - RETURN: v8_7: 21490.73 00:54:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "21490.73"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1524.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "7014.88"}}} 00:54:46 INFO - Return code: 0 00:54:46 INFO - # TBPL SUCCESS # 00:54:46 INFO - Running post-action listener: _resource_record_post_action 00:54:46 INFO - Running post-run listener: _resource_record_post_run 00:54:47 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 507346944; Read time: 16; Write time: 494324 00:54:47 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 220389376; Read time: 0; Write time: 290940 00:54:48 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 280604672; Read time: 16; Write time: 173260 00:54:48 INFO - Running post-run listener: _upload_blobber_files 00:54:48 INFO - Blob upload gear active. 00:54:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 00:54:48 INFO - Copying logs to upload dir... 00:54:48 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1271.144972 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-11-21 00:54:47.817769) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-21 00:54:47.821556) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010116 build_url: 'https://queue.taskcluster.net/v1/task/BCfS6yFRSTiOBMbt_Kcovw/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-21 00:54:47.873172) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:54:47.873502) ========= 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=1d09c2d9237d126f9c84bbb400000198-1448089714.555699-226078310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004659 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-21 00:54:47.922917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-21 00:54:47.923244) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-21 00:54:47.923569) ========= ========= Total master_lag: 0.22 =========