builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1448247517.59 results: success (0) buildid: 20151122173001 builduid: af89391b55e5410d9357ecebf29b3ca8 revision: 5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.590425) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.590932) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.591228) ========= 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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009048 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.625868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.626139) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.660304) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.660563) ========= 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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004672 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.718855) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.719155) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.719514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.719789) ========= 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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-22 18:58:37-- 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% 211M=0s 2015-11-22 18:58:38 (211 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.178440 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.909811) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.910104) ========= 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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017114 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.960876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:37.961214) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb --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/b2g-inbound --rev 5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb --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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-22 18:58:38,197 truncating revision to first 12 chars 2015-11-22 18:58:38,197 Setting DEBUG logging. 2015-11-22 18:58:38,197 attempt 1/10 2015-11-22 18:58:38,197 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/5a8bc0ddc048?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-22 18:58:38,530 unpacking tar archive at: b2g-inbound-5a8bc0ddc048/testing/mozharness/ program finished with exit code 0 elapsedTime=0.971109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:38.955429) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:38.955793) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:38.966558) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:38.967092) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-22 18:58:38.967741) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-22 18:58:38.968218) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-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', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:58:39 INFO - MultiFileLogger online at 20151122 18:58:39 in /builds/slave/test-pgo 18:58:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 18:58:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:58:39 INFO - {'append_to_log': False, 18:58:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:58:39 INFO - 'blob_upload_branch': 'B2G-Inbound', 18:58:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:58:39 INFO - 'branch': 'B2G-Inbound', 18:58:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:58:39 INFO - 'config_files': ('talos/linux_config.py',), 18:58:39 INFO - 'default_actions': ('clobber', 18:58:39 INFO - 'read-buildbot-config', 18:58:39 INFO - 'download-and-extract', 18:58:39 INFO - 'populate-webroot', 18:58:39 INFO - 'create-virtualenv', 18:58:39 INFO - 'install', 18:58:39 INFO - 'run-tests'), 18:58:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:58:39 INFO - 'download_minidump_stackwalk': True, 18:58:39 INFO - 'download_symbols': 'ondemand', 18:58:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:58:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:58:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:58:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:58:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:58:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:58:39 INFO - 'installer_path': 'installer.exe', 18:58:39 INFO - 'log_level': 'info', 18:58:39 INFO - 'log_name': 'talos', 18:58:39 INFO - 'log_to_console': True, 18:58:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:58:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:58:39 INFO - 'opt_config_files': (), 18:58:39 INFO - 'pip_index': False, 18:58:39 INFO - 'sps_profile': False, 18:58:39 INFO - 'sps_profile_interval': 0, 18:58:39 INFO - 'suite': 'dromaeojs', 18:58:39 INFO - 'system_bits': '64', 18:58:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:58:39 INFO - 'title': 'talos-linux64-ix-016', 18:58:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:58:39 INFO - 'use_talos_json': True, 18:58:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:58:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:58:39 INFO - 'work_dir': 'build'} 18:58:39 INFO - ##### 18:58:39 INFO - ##### Running clobber step. 18:58:39 INFO - ##### 18:58:39 INFO - Running pre-action listener: _resource_record_pre_action 18:58:39 INFO - Running main action method: clobber 18:58:39 INFO - rmtree: /builds/slave/test-pgo/build 18:58:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:58:40 INFO - Running post-action listener: _resource_record_post_action 18:58:40 INFO - ##### 18:58:40 INFO - ##### Running read-buildbot-config step. 18:58:40 INFO - ##### 18:58:40 INFO - Running pre-action listener: _resource_record_pre_action 18:58:40 INFO - Running main action method: read_buildbot_config 18:58:40 INFO - Using buildbot properties: 18:58:40 INFO - { 18:58:40 INFO - "properties": { 18:58:40 INFO - "buildnumber": 994, 18:58:40 INFO - "product": "firefox", 18:58:40 INFO - "script_repo_revision": "production", 18:58:40 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 18:58:40 INFO - "repository": "", 18:58:40 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 18:58:40 INFO - "buildid": "20151122173001", 18:58:40 INFO - "slavename": "talos-linux64-ix-016", 18:58:40 INFO - "pgo_build": "True", 18:58:40 INFO - "basedir": "/builds/slave/test-pgo", 18:58:40 INFO - "project": "", 18:58:40 INFO - "platform": "ubuntu64_hw", 18:58:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:58:40 INFO - "slavebuilddir": "test", 18:58:40 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 18:58:40 INFO - "branch": "b2g-inbound", 18:58:40 INFO - "repo_path": "integration/b2g-inbound", 18:58:40 INFO - "stage_platform": "linux64-pgo", 18:58:40 INFO - "builduid": "af89391b55e5410d9357ecebf29b3ca8", 18:58:40 INFO - "revision": "5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb" 18:58:40 INFO - }, 18:58:40 INFO - "sourcestamp": { 18:58:40 INFO - "repository": "", 18:58:40 INFO - "hasPatch": false, 18:58:40 INFO - "project": "", 18:58:40 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 18:58:40 INFO - "changes": [ 18:58:40 INFO - { 18:58:40 INFO - "category": null, 18:58:40 INFO - "files": [ 18:58:40 INFO - { 18:58:40 INFO - "url": null, 18:58:40 INFO - "name": "https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:58:40 INFO - } 18:58:40 INFO - ], 18:58:40 INFO - "repository": "", 18:58:40 INFO - "rev": "5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb", 18:58:40 INFO - "who": "sendchange", 18:58:40 INFO - "when": 1448247488, 18:58:40 INFO - "number": 6693818, 18:58:40 INFO - "comments": "", 18:58:40 INFO - "project": "", 18:58:40 INFO - "at": "Sun 22 Nov 2015 18:58:08", 18:58:40 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 18:58:40 INFO - "revlink": "", 18:58:40 INFO - "properties": [ 18:58:40 INFO - [ 18:58:40 INFO - "buildid", 18:58:40 INFO - "20151122173001", 18:58:40 INFO - "Change" 18:58:40 INFO - ], 18:58:40 INFO - [ 18:58:40 INFO - "builduid", 18:58:40 INFO - "af89391b55e5410d9357ecebf29b3ca8", 18:58:40 INFO - "Change" 18:58:40 INFO - ], 18:58:40 INFO - [ 18:58:40 INFO - "pgo_build", 18:58:40 INFO - "True", 18:58:40 INFO - "Change" 18:58:40 INFO - ] 18:58:40 INFO - ], 18:58:40 INFO - "revision": "5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb" 18:58:40 INFO - } 18:58:40 INFO - ], 18:58:40 INFO - "revision": "5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb" 18:58:40 INFO - } 18:58:40 INFO - } 18:58:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:58:40 INFO - Running post-action listener: _resource_record_post_action 18:58:40 INFO - ##### 18:58:40 INFO - ##### Running download-and-extract step. 18:58:40 INFO - ##### 18:58:40 INFO - Running pre-action listener: _resource_record_pre_action 18:58:40 INFO - Running main action method: download_and_extract 18:58:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:40 INFO - https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:58:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/test_packages.json 18:58:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/test_packages.json 18:58:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:58:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:58:41 INFO - Downloaded 1302 bytes. 18:58:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:58:41 INFO - Using the following test package requirements: 18:58:41 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:58:41 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:58:41 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'jsshell-linux-x86_64.zip'], 18:58:41 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:58:41 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:58:41 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:58:41 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:58:41 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:58:41 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:58:41 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:58:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:58:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:41 INFO - https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:58:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:58:42 INFO - Downloaded 22523512 bytes. 18:58:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:58:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:58:43 INFO - Return code: 0 18:58:43 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:43 INFO - https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:58:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:58:46 INFO - Downloaded 22523512 bytes. 18:58:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:58:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:58:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:58:48 INFO - Return code: 0 18:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:48 INFO - https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:58:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:58:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:58:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:58:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:58:49 INFO - Downloaded 11130997 bytes. 18:58:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:58:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:58:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:58:49 INFO - Return code: 0 18:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:49 INFO - https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:58:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:58:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:58:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:58:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:58:51 INFO - Downloaded 57446296 bytes. 18:58:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:58:51 INFO - mkdir: /builds/slave/test-pgo/properties 18:58:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:58:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:58:51 INFO - Contents: 18:58:51 INFO - build_url:https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:58:51 INFO - Running post-action listener: _resource_record_post_action 18:58:51 INFO - Running post-action listener: set_extra_try_arguments 18:58:51 INFO - ##### 18:58:51 INFO - ##### Running populate-webroot step. 18:58:51 INFO - ##### 18:58:51 INFO - Running pre-action listener: _resource_record_pre_action 18:58:51 INFO - Running main action method: populate_webroot 18:58:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:58:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:58:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:58:51 INFO - '../fennec_ids.txt'], 18:58:51 INFO - 'tests': ['tcheck2']}, 18:58:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:58:51 INFO - '--tppagecycles', 18:58:51 INFO - '7'], 18:58:51 INFO - 'tests': ['tsvgm']}}, 18:58:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:58:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:58:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:58:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:58:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:58:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:58:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'tests': ['damp', 'tps']}, 18:58:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['damp', 'tps']}, 18:58:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:58:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:58:51 INFO - 'other': {'tests': ['a11yr', 18:58:51 INFO - 'ts_paint', 18:58:51 INFO - 'tpaint', 18:58:51 INFO - 'sessionrestore', 18:58:51 INFO - 'sessionrestore_no_auto_restore']}, 18:58:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['a11yr', 18:58:51 INFO - 'ts_paint', 18:58:51 INFO - 'tpaint', 18:58:51 INFO - 'sessionrestore', 18:58:51 INFO - 'sessionrestore_no_auto_restore']}, 18:58:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['a11yr', 18:58:51 INFO - 'ts_paint', 18:58:51 INFO - 'tpaint', 18:58:51 INFO - 'sessionrestore', 18:58:51 INFO - 'sessionrestore_no_auto_restore']}, 18:58:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['a11yr', 18:58:51 INFO - 'ts_paint', 18:58:51 INFO - 'tpaint', 18:58:51 INFO - 'sessionrestore', 18:58:51 INFO - 'sessionrestore_no_auto_restore']}, 18:58:51 INFO - 'other_l64': {'tests': ['a11yr', 18:58:51 INFO - 'ts_paint', 18:58:51 INFO - 'tpaint', 18:58:51 INFO - 'sessionrestore', 18:58:51 INFO - 'sessionrestore_no_auto_restore']}, 18:58:51 INFO - 'other_nol64': {'tests': ['a11yr', 18:58:51 INFO - 'ts_paint', 18:58:51 INFO - 'tpaint', 18:58:51 INFO - 'sessionrestore', 18:58:51 INFO - 'sessionrestore_no_auto_restore']}, 18:58:51 INFO - 'svgr': {'tests': ['tsvgx', 18:58:51 INFO - 'tsvgr_opacity', 18:58:51 INFO - 'tart', 18:58:51 INFO - 'tscrollx', 18:58:51 INFO - 'cart']}, 18:58:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['tsvgx', 18:58:51 INFO - 'tsvgr_opacity', 18:58:51 INFO - 'tart', 18:58:51 INFO - 'tscrollx', 18:58:51 INFO - 'cart']}, 18:58:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'tests': ['tp5o']}, 18:58:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'talos_options': ['--e10s'], 18:58:51 INFO - 'tests': ['tp5o']}, 18:58:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'talos_options': ['--xperf_path', 18:58:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:58:51 INFO - 'tests': ['tp5n']}, 18:58:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:58:51 INFO - 'talos_options': ['--e10s', 18:58:51 INFO - '--xperf_path', 18:58:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:58:51 INFO - 'tests': ['tp5n']}}, 18:58:51 INFO - 'talos.zip': {'path': '', 18:58:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:58:51 INFO - Running post-action listener: _resource_record_post_action 18:58:51 INFO - ##### 18:58:51 INFO - ##### Running create-virtualenv step. 18:58:51 INFO - ##### 18:58:51 INFO - Running pre-action listener: _resource_record_pre_action 18:58:51 INFO - Running main action method: create_virtualenv 18:58:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:58:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:58:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:58:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:58:51 INFO - Using real prefix '/usr' 18:58:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:58:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:58:53 INFO - Installing pip.................done. 18:58:53 INFO - Return code: 0 18:58:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:53 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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:58:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:58:53 INFO - Using env: {'DISPLAY': ':0', 18:58:53 INFO - 'HOME': '/home/cltbld', 18:58:53 INFO - 'LANG': 'en_US.UTF-8', 18:58:53 INFO - 'LANGUAGE': 'en_US:en', 18:58:53 INFO - 'LOGNAME': 'cltbld', 18:58:53 INFO - 'MAIL': '/var/mail/cltbld', 18:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:53 INFO - 'MOZ_NO_REMOTE': '1', 18:58:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:53 INFO - 'NO_EM_RESTART': '1', 18:58:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:53 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:53 INFO - 'SHELL': '/bin/bash', 18:58:53 INFO - 'SHLVL': '1', 18:58:53 INFO - 'TERM': 'linux', 18:58:53 INFO - 'TMOUT': '86400', 18:58:53 INFO - 'USER': 'cltbld', 18:58:53 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:53 INFO - '_': '/tools/buildbot/bin/python'} 18:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:58:54 INFO - Cleaning up... 18:58:54 INFO - Return code: 0 18:58:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:58: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')]} 18:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58: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')]} 18:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:54 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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:58:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:58:54 INFO - Using env: {'DISPLAY': ':0', 18:58:54 INFO - 'HOME': '/home/cltbld', 18:58:54 INFO - 'LANG': 'en_US.UTF-8', 18:58:54 INFO - 'LANGUAGE': 'en_US:en', 18:58:54 INFO - 'LOGNAME': 'cltbld', 18:58:54 INFO - 'MAIL': '/var/mail/cltbld', 18:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:54 INFO - 'MOZ_NO_REMOTE': '1', 18:58:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:54 INFO - 'NO_EM_RESTART': '1', 18:58:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:54 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:54 INFO - 'SHELL': '/bin/bash', 18:58:54 INFO - 'SHLVL': '1', 18:58:54 INFO - 'TERM': 'linux', 18:58:54 INFO - 'TMOUT': '86400', 18:58:54 INFO - 'USER': 'cltbld', 18:58:54 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:54 INFO - '_': '/tools/buildbot/bin/python'} 18:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:54 INFO - Downloading/unpacking psutil>=0.7.1 18:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:58:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:58:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:58:56 INFO - Installing collected packages: psutil 18:58:56 INFO - Running setup.py install for psutil 18:58:56 INFO - building 'psutil._psutil_linux' extension 18:58:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:58:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:58:56 INFO - building 'psutil._psutil_posix' extension 18:58:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:58:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:58:56 INFO - Successfully installed psutil 18:58:56 INFO - Cleaning up... 18:58:56 INFO - Return code: 0 18:58:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:58: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')]} 18:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58: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')]} 18:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:56 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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:58:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:58:56 INFO - Using env: {'DISPLAY': ':0', 18:58:56 INFO - 'HOME': '/home/cltbld', 18:58:56 INFO - 'LANG': 'en_US.UTF-8', 18:58:56 INFO - 'LANGUAGE': 'en_US:en', 18:58:56 INFO - 'LOGNAME': 'cltbld', 18:58:56 INFO - 'MAIL': '/var/mail/cltbld', 18:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:56 INFO - 'MOZ_NO_REMOTE': '1', 18:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:56 INFO - 'NO_EM_RESTART': '1', 18:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:56 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:56 INFO - 'SHELL': '/bin/bash', 18:58:56 INFO - 'SHLVL': '1', 18:58:56 INFO - 'TERM': 'linux', 18:58:56 INFO - 'TMOUT': '86400', 18:58:56 INFO - 'USER': 'cltbld', 18:58:56 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:56 INFO - '_': '/tools/buildbot/bin/python'} 18:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:58:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:58:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:58:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:58:58 INFO - Installing collected packages: mozsystemmonitor 18:58:58 INFO - Running setup.py install for mozsystemmonitor 18:58:58 INFO - Successfully installed mozsystemmonitor 18:58:58 INFO - Cleaning up... 18:58:58 INFO - Return code: 0 18:58:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:58: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')]} 18:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58: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')]} 18:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:58 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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:58:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:58:58 INFO - Using env: {'DISPLAY': ':0', 18:58:58 INFO - 'HOME': '/home/cltbld', 18:58:58 INFO - 'LANG': 'en_US.UTF-8', 18:58:58 INFO - 'LANGUAGE': 'en_US:en', 18:58:58 INFO - 'LOGNAME': 'cltbld', 18:58:58 INFO - 'MAIL': '/var/mail/cltbld', 18:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:58 INFO - 'MOZ_NO_REMOTE': '1', 18:58:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:58 INFO - 'NO_EM_RESTART': '1', 18:58:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:58 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:58 INFO - 'SHELL': '/bin/bash', 18:58:58 INFO - 'SHLVL': '1', 18:58:58 INFO - 'TERM': 'linux', 18:58:58 INFO - 'TMOUT': '86400', 18:58:58 INFO - 'USER': 'cltbld', 18:58:58 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:58 INFO - '_': '/tools/buildbot/bin/python'} 18:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:58 INFO - Downloading/unpacking blobuploader==1.2.4 18:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:00 INFO - Downloading blobuploader-1.2.4.tar.gz 18:59:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:59:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:59:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:59:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:59:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:01 INFO - Downloading docopt-0.6.1.tar.gz 18:59:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:59:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:59:01 INFO - Installing collected packages: blobuploader, requests, docopt 18:59:01 INFO - Running setup.py install for blobuploader 18:59:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:59:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:59:01 INFO - Running setup.py install for requests 18:59:01 INFO - Running setup.py install for docopt 18:59:01 INFO - Successfully installed blobuploader requests docopt 18:59:01 INFO - Cleaning up... 18:59:01 INFO - Return code: 0 18:59:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:01 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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:59:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:59:01 INFO - Using env: {'DISPLAY': ':0', 18:59:01 INFO - 'HOME': '/home/cltbld', 18:59:01 INFO - 'LANG': 'en_US.UTF-8', 18:59:01 INFO - 'LANGUAGE': 'en_US:en', 18:59:01 INFO - 'LOGNAME': 'cltbld', 18:59:01 INFO - 'MAIL': '/var/mail/cltbld', 18:59:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:01 INFO - 'MOZ_NO_REMOTE': '1', 18:59:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:01 INFO - 'NO_EM_RESTART': '1', 18:59:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:59:01 INFO - 'PWD': '/builds/slave/test-pgo', 18:59:01 INFO - 'SHELL': '/bin/bash', 18:59:01 INFO - 'SHLVL': '1', 18:59:01 INFO - 'TERM': 'linux', 18:59:01 INFO - 'TMOUT': '86400', 18:59:01 INFO - 'USER': 'cltbld', 18:59:01 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:59:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:01 INFO - '_': '/tools/buildbot/bin/python'} 18:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:59:02 INFO - Running setup.py (path:/tmp/pip-twQD54-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:59:02 INFO - Running setup.py (path:/tmp/pip-M4vb7K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:59:02 INFO - Running setup.py (path:/tmp/pip-yXYKQS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:59:02 INFO - Running setup.py (path:/tmp/pip-jzTQew-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:59:02 INFO - Running setup.py (path:/tmp/pip-qCdjhY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:59:02 INFO - Running setup.py (path:/tmp/pip-AyW91w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:59:02 INFO - Running setup.py (path:/tmp/pip-OjQ9X9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:59:02 INFO - Running setup.py (path:/tmp/pip-6cmXqG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:59:02 INFO - Running setup.py (path:/tmp/pip-upHfvM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:59:02 INFO - Running setup.py (path:/tmp/pip-hnJSPH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:59:02 INFO - Running setup.py (path:/tmp/pip-eEGAzc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:59:02 INFO - Running setup.py (path:/tmp/pip-a67I9q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:59:02 INFO - Running setup.py (path:/tmp/pip-pmo6jU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:59:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:59:02 INFO - Running setup.py (path:/tmp/pip-wCW3CX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:59:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:59:03 INFO - Running setup.py (path:/tmp/pip-dYMF9j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:59:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:59:03 INFO - Running setup.py (path:/tmp/pip-8piAJY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:59:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:59:03 INFO - Running setup.py (path:/tmp/pip-Rht18w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:59:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:59:03 INFO - Running setup.py install for manifestparser 18:59:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:59:03 INFO - Running setup.py install for mozcrash 18:59:03 INFO - Running setup.py install for mozdebug 18:59:03 INFO - Running setup.py install for mozdevice 18:59:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:59:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:59:03 INFO - Running setup.py install for mozfile 18:59:03 INFO - Running setup.py install for mozhttpd 18:59:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:59:03 INFO - Running setup.py install for mozinfo 18:59:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:59:03 INFO - Running setup.py install for mozInstall 18:59:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Running setup.py install for mozleak 18:59:04 INFO - Running setup.py install for mozlog 18:59:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Running setup.py install for moznetwork 18:59:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Running setup.py install for mozprocess 18:59:04 INFO - Running setup.py install for mozprofile 18:59:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Running setup.py install for mozrunner 18:59:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:59:04 INFO - Running setup.py install for mozscreenshot 18:59:04 INFO - Running setup.py install for moztest 18:59:04 INFO - Running setup.py install for mozversion 18:59:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:59:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:59:05 INFO - Cleaning up... 18:59:05 INFO - Return code: 0 18:59:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:05 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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:59:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:59:05 INFO - Using env: {'DISPLAY': ':0', 18:59:05 INFO - 'HOME': '/home/cltbld', 18:59:05 INFO - 'LANG': 'en_US.UTF-8', 18:59:05 INFO - 'LANGUAGE': 'en_US:en', 18:59:05 INFO - 'LOGNAME': 'cltbld', 18:59:05 INFO - 'MAIL': '/var/mail/cltbld', 18:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:05 INFO - 'MOZ_NO_REMOTE': '1', 18:59:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:05 INFO - 'NO_EM_RESTART': '1', 18:59:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:59:05 INFO - 'PWD': '/builds/slave/test-pgo', 18:59:05 INFO - 'SHELL': '/bin/bash', 18:59:05 INFO - 'SHLVL': '1', 18:59:05 INFO - 'TERM': 'linux', 18:59:05 INFO - 'TMOUT': '86400', 18:59:05 INFO - 'USER': 'cltbld', 18:59:05 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:05 INFO - '_': '/tools/buildbot/bin/python'} 18:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:59:05 INFO - Running setup.py (path:/tmp/pip-Y5yhKW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:59:05 INFO - Running setup.py (path:/tmp/pip-GuqZ6g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:59:05 INFO - Running setup.py (path:/tmp/pip-Xl3tQc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:59:05 INFO - Running setup.py (path:/tmp/pip-joWQ5X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:59:05 INFO - Running setup.py (path:/tmp/pip-ZbZJgt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:59:05 INFO - Running setup.py (path:/tmp/pip-7_dlDN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:59:05 INFO - Running setup.py (path:/tmp/pip-CYxK5g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:59:05 INFO - Running setup.py (path:/tmp/pip-kbXt0p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:59:05 INFO - Running setup.py (path:/tmp/pip-7Fq9Qy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:59:05 INFO - Running setup.py (path:/tmp/pip-5XWmUm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:59:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:59:05 INFO - Running setup.py (path:/tmp/pip-9sHDfb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:59:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:59:06 INFO - Running setup.py (path:/tmp/pip-BtJiDq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:59:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:59:06 INFO - Running setup.py (path:/tmp/pip-Yz2b72-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:59:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:59:06 INFO - Running setup.py (path:/tmp/pip-RZEZg7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:59:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:59:06 INFO - Running setup.py (path:/tmp/pip-v4CBba-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:59:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:59:06 INFO - Running setup.py (path:/tmp/pip-pyiWF6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:59:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:59:06 INFO - Running setup.py (path:/tmp/pip-4E5F6t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:59:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:08 INFO - Downloading blessings-1.5.1.tar.gz 18:59:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:59:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:59:08 INFO - Installing collected packages: blessings 18:59:08 INFO - Running setup.py install for blessings 18:59:08 INFO - Successfully installed blessings 18:59:08 INFO - Cleaning up... 18:59:08 INFO - Return code: 0 18:59:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:59:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:59:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:59:08 INFO - Reading from file tmpfile_stdout 18:59:08 INFO - Current package versions: 18:59:08 INFO - argparse == 1.2.1 18:59:08 INFO - blessings == 1.5.1 18:59:08 INFO - blobuploader == 1.2.4 18:59:08 INFO - docopt == 0.6.1 18:59:08 INFO - manifestparser == 1.1 18:59:08 INFO - mozInstall == 1.12 18:59:08 INFO - mozcrash == 0.16 18:59:08 INFO - mozdebug == 0.1 18:59:08 INFO - mozdevice == 0.47 18:59:08 INFO - mozfile == 1.2 18:59:08 INFO - mozhttpd == 0.7 18:59:08 INFO - mozinfo == 0.9 18:59:08 INFO - mozleak == 0.1 18:59:08 INFO - mozlog == 3.0 18:59:08 INFO - moznetwork == 0.27 18:59:08 INFO - mozprocess == 0.22 18:59:08 INFO - mozprofile == 0.27 18:59:08 INFO - mozrunner == 6.11 18:59:08 INFO - mozscreenshot == 0.1 18:59:08 INFO - mozsystemmonitor == 0.0 18:59:08 INFO - moztest == 0.7 18:59:08 INFO - mozversion == 1.4 18:59:08 INFO - psutil == 3.1.1 18:59:08 INFO - requests == 1.2.3 18:59:08 INFO - wsgiref == 0.1.2 18:59:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7fd0ce971c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfd7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, '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': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:59:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:59:08 INFO - Using env: {'DISPLAY': ':0', 18:59:08 INFO - 'HOME': '/home/cltbld', 18:59:08 INFO - 'LANG': 'en_US.UTF-8', 18:59:08 INFO - 'LANGUAGE': 'en_US:en', 18:59:08 INFO - 'LOGNAME': 'cltbld', 18:59:08 INFO - 'MAIL': '/var/mail/cltbld', 18:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:08 INFO - 'MOZ_NO_REMOTE': '1', 18:59:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:08 INFO - 'NO_EM_RESTART': '1', 18:59:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:59:08 INFO - 'PWD': '/builds/slave/test-pgo', 18:59:08 INFO - 'SHELL': '/bin/bash', 18:59:08 INFO - 'SHLVL': '1', 18:59:08 INFO - 'TERM': 'linux', 18:59:08 INFO - 'TMOUT': '86400', 18:59:08 INFO - 'USER': 'cltbld', 18:59:08 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:08 INFO - '_': '/tools/buildbot/bin/python'} 18:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:59:08 INFO - Cleaning up... 18:59:08 INFO - Return code: 0 18:59:08 INFO - Running post-action listener: _resource_record_post_action 18:59:08 INFO - Running post-action listener: _start_resource_monitoring 18:59:09 INFO - Starting resource monitoring. 18:59:09 INFO - ##### 18:59:09 INFO - ##### Running install step. 18:59:09 INFO - ##### 18:59:09 INFO - Running pre-action listener: _resource_record_pre_action 18:59:09 INFO - Running main action method: install 18:59:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:59:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:59:09 INFO - Reading from file tmpfile_stdout 18:59:09 INFO - Detecting whether we're running mozinstall >=1.0... 18:59:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:59:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:59:09 INFO - Reading from file tmpfile_stdout 18:59:09 INFO - Output received: 18:59:09 INFO - Usage: mozinstall [options] installer 18:59:09 INFO - Options: 18:59:09 INFO - -h, --help show this help message and exit 18:59:09 INFO - -d DEST, --destination=DEST 18:59:09 INFO - Directory to install application into. [default: 18:59:09 INFO - "/builds/slave/test-pgo"] 18:59:09 INFO - --app=APP Application being installed. [default: firefox] 18:59:09 INFO - mkdir: /builds/slave/test-pgo/build/application 18:59:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:59:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:59:24 INFO - Reading from file tmpfile_stdout 18:59:24 INFO - Output received: 18:59:24 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:59:24 INFO - Running post-action listener: _resource_record_post_action 18:59:24 INFO - ##### 18:59:24 INFO - ##### Running run-tests step. 18:59:24 INFO - ##### 18:59:24 INFO - Running pre-action listener: _resource_record_pre_action 18:59:24 INFO - Running main action method: run_tests 18:59:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:59:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:59:24 INFO - Python 2.7.3 18:59:24 INFO - Return code: 0 18:59:24 INFO - grabbing minidump binary from tooltool 18:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:24 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 0x1e41d70>, '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 0x1e42b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:59:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:59:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:59:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:59:24 INFO - Return code: 0 18:59:24 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:59:24 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:59:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:59:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:59:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:59:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:59:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost 18:59:24 INFO - Using env: {'DISPLAY': ':0', 18:59:24 INFO - 'HOME': '/home/cltbld', 18:59:24 INFO - 'LANG': 'en_US.UTF-8', 18:59:24 INFO - 'LANGUAGE': 'en_US:en', 18:59:24 INFO - 'LOGNAME': 'cltbld', 18:59:24 INFO - 'MAIL': '/var/mail/cltbld', 18:59:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:59:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:24 INFO - 'MOZ_NO_REMOTE': '1', 18:59:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:59:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:24 INFO - 'NO_EM_RESTART': '1', 18:59:24 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:59:24 INFO - 'PWD': '/builds/slave/test-pgo', 18:59:24 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:59:24 INFO - 'SHELL': '/bin/bash', 18:59:24 INFO - 'SHLVL': '1', 18:59:24 INFO - 'TERM': 'linux', 18:59:24 INFO - 'TMOUT': '86400', 18:59:24 INFO - 'USER': 'cltbld', 18:59:24 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1448236923.442875-404014853', 18:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:24 INFO - '_': '/tools/buildbot/bin/python'} 18:59:24 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] with output_timeout 3600 18:59:24 INFO - mozversion INFO | application_buildid: 20151122173001 18:59:24 INFO - mozversion INFO | application_changeset: 5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb 18:59:24 INFO - mozversion INFO | application_display_name: Nightly 18:59:24 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:59:24 INFO - mozversion INFO | application_name: Firefox 18:59:24 INFO - mozversion INFO | application_remotingname: firefox 18:59:24 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 18:59:24 INFO - mozversion INFO | application_vendor: Mozilla 18:59:24 INFO - mozversion INFO | application_version: 45.0a1 18:59:24 INFO - mozversion INFO | platform_buildid: 20151122173001 18:59:24 INFO - mozversion INFO | platform_changeset: 5a8bc0ddc048ae78c930dffd6f9d6788f4bc5ddb 18:59:24 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 18:59:24 INFO - mozversion INFO | platform_version: 45.0a1 18:59:24 INFO - 2015-11-22 18:59:24,422 DEBUG : using testdate: 1448247564 18:59:24 INFO - 2015-11-22 18:59:24,422 DEBUG : actual date: 1448247564 18:59:24 INFO - 2015-11-22 18:59:24,430 INFO : starting webserver on 'localhost:48008' 18:59:24 INFO - 2015-11-22 18:59:24,431 INFO : Starting test suite talos-linux64-ix-016 18:59:24 INFO - 2015-11-22 18:59:24,432 INFO : Starting test dromaeo_css 18:59:24 INFO - 2015-11-22 18:59:24,432 DEBUG : operating with platform_type : linux_ 18:59:24 INFO - 2015-11-22 18:59:24,432 INFO : Initialising browser for dromaeo_css test... 18:59:24 INFO - 2015-11-22 18:59:24,449 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTHfYrQ/profile http://localhost:48008/getInfo.html 18:59:28 INFO - 2015-11-22 18:59:28,625 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:59:28 INFO - 2015-11-22 18:59:28,625 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:59:28 INFO - 2015-11-22 18:59:28,625 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:59:28 INFO - 2015-11-22 18:59:28,625 DEBUG : BROWSER_OUTPUT: __metrics 18:59:28 INFO - 2015-11-22 18:59:28,762 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:59:29 INFO - 2015-11-22 18:59:29,918 INFO : Browser initialized. 18:59:29 INFO - 2015-11-22 18:59:29,919 INFO : Running cycle 1/1 for dromaeo_css test... 18:59:29 INFO - 2015-11-22 18:59:29,919 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTHfYrQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:59:41 INFO - 2015-11-22 18:59:41,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 142086144 18:59:41 INFO - 2015-11-22 18:59:41,619 DEBUG : BROWSER_OUTPUT: 19:00:32 INFO - 2015-11-22 19:00:32,350 DEBUG : BROWSER_OUTPUT: 1448247632349 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] 19:02:42 INFO - 2015-11-22 19:02:42,653 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48008/tests/dromaeo/cssquery-ext.html) 19:02:43 INFO - 2015-11-22 19:02:43,766 DEBUG : BROWSER_OUTPUT: RSS: Main: 251088896 19:02:43 INFO - 2015-11-22 19:02:43,766 DEBUG : BROWSER_OUTPUT: 19:05:46 INFO - 2015-11-22 19:05:46,202 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/dromaeo/cssquery-ext.html (next: http://localhost:48008/tests/dromaeo/cssquery-jquery.html) 19:05:46 INFO - 2015-11-22 19:05:46,768 DEBUG : BROWSER_OUTPUT: RSS: Main: 215986176 19:05:46 INFO - 2015-11-22 19:05:46,768 DEBUG : BROWSER_OUTPUT: 19:08:48 INFO - 2015-11-22 19:08:48,717 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48008/tests/dromaeo/cssquery-mootools.html) 19:08:49 INFO - 2015-11-22 19:08:49,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 214183936 19:08:49 INFO - 2015-11-22 19:08:49,284 DEBUG : BROWSER_OUTPUT: 19:11:51 INFO - 2015-11-22 19:11:51,063 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48008/tests/dromaeo/cssquery-prototype.html) 19:11:51 INFO - 2015-11-22 19:11:51,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 201056256 19:11:51 INFO - 2015-11-22 19:11:51,631 DEBUG : BROWSER_OUTPUT: 19:14:54 INFO - 2015-11-22 19:14:54,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48008/tests/dromaeo/cssquery-yui.html) 19:14:54 INFO - 2015-11-22 19:14:54,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 201723904 19:14:54 INFO - 2015-11-22 19:14:54,579 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,145 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/dromaeo/cssquery-yui.html (next: http://localhost:48008/tests/dromaeo/cssquery-dojo.html) 19:17:57 INFO - 2015-11-22 19:17:57,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 245972992 19:17:57 INFO - 2015-11-22 19:17:57,395 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,396 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:17:57 INFO - 2015-11-22 19:17:57,397 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:17:57 INFO - 2015-11-22 19:17:57,397 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:17:57 INFO - 2015-11-22 19:17:57,397 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:17:57 INFO - 2015-11-22 19:17:57,398 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3203.19920019995;3248.9025329240103;3294.9176270593234;3303.1907182740233;3322.5846769153854;5284.947150528494;5387.946120538803;5465.316835395571;5471.425500322463;5497.367802702691;5423;5472.015037293286;5489.807856725006;5491.99999999999;5550.972245138779;4979.55184033436;5002.349694539723;5026.773795179208;5027.798888044474;5056.494350564951;4923.138450771118;5086.931744333694;5088.77100530477;5142.04872098663;5148.150555158394;4972.453030166696;4981.1283025470575;5080.085584594772;5092.8472145835685;5101;5062.1900495920845;5062.670926389773;5067.6452648314635;5078.720670363128;5146.125158722989;5011.899762004739;5056.519630635084;5073.543381095682;5082.847514574569;5088.872778180538;5146.588272938156;5212.478752124795;5252.842414727565;5346.91979620306;5420.07858664028;5922.46697797196;5953.523718102541;5959.582829201958;5970.492508136814;5972.462478376923;6005.939940600581;6011;6015.3383127855905;6018.8495287617725;6019.548533859935;5363.141897296453;5454.945450545523;5458.563314934796;5466.863328416742;5591.832245032656;6057.515398768088;6069.787557435468;6072.574919755663;6075.058365953284;6085.452309292096;5807.477327040587;5815.621984570989;5863.237779088777;5868.941310586925;5877.617954832922;5671.773129074873;5773.971130144322;5831.79588714393;5904.025835737141;5954.374790647006;5854.473097421167;5857.677827719516;5865.20819689345;5870.589058765845;5893.5579831512805;5687.146927960839;5870.060790273621;5881;5883.14694369314;5889;4493.662975276868;4493.842715504941;4557.931631025537;4576.519465456146;4599.67802253839;4035.3543433050572;4036.0313524754433;4041.515018197835;4079.0414252650603;4081.836726530965;5430.402655707869;5577.553795696334;5581.441855814467;5584.776608935678;5588.050031494656;5777.62445441045;5803.82588522345;5853.590248682552;5864.765409383662;5917;5500.7524661390335;5504.119340905436;5573.080441727069;5587.776488940478;5632;5141.511556402136;5154.561862241677;5161.174212126042;5164.741762911839;5200.063988482034;5900.43945825146;6007.639541627516;6014.428629280212;6124.295705993779;6136.018237082046;5914.645121292736;5960.970195148997;5981.3420523743225;6032.819015429544;6035.155078289131;6005.699715014144;6069.393060693896;6072.90890636632;6088.299845517822;6102.359252278447;5791.276090488689;5826.504747096545;5880.588358814842;5880.970595146997;5883.058710606282;5911.408859114054;5920.141579471038;5931.851703707442;5949.910751338647;5995.670238136944;5754.597178197486;5843.415658434123;5845;5850.64896106235;5851.297844258716;5655.066913959316;5807.448292412214;5812.651240925558;5821.155932389865;5822.359540450631;5602.299712535933;5602.943970560243;5659.434056594308;5666.433356664301;5702.116171993348;5875.265591801025;5894.734736936763;5932.436418540232;5942.078977758454;5980.790672326448;5608.38307786135;5708.771649133988;5803.216565763568;5805.10020946754;5812.18629391894 19:17:57 INFO - 2015-11-22 19:17:57,402 DEBUG : BROWSER_OUTPUT: |1;ext.html;557.6013150597322;593.8218534439669;597.8087012156112;599.7930713903703;600.1238192239331;29964.550531742014;30577.08268751938;40754.38868416976;40986.38520422196;41014.58985410153;43811;44136.558634413646;44150;44173;44359;7609.124950630693;8757.737267881957;8922.509261990588;8930.86603700945;8932.821343573125;7784.143744188134;7823.960880195591;7879.251471110236;7880.763577092668;10586.894131058667;10498.370097794119;10566.418846963414;10572.577096916115;10590.947045264802;10593.099586535154;10418.531166097544;10471.267011309212;10506;10508.579656813718;10514.369137851716;10308.896911030944;10409.739756506147;10437.11284540815;10438.269321147523;10445.112165465944;40230.39654405157;40523.797381012904;40653.1869362614;40659.796701016305;40699.796501017605;3083.922901927447;3100.689931006904;3143.6070491188602;3148.8583013764205;3215.340855124717;30485.542716859265;30790;31762.841185794154;31905.042848714576;32273.1931701707;3864.1692036212066;3918.3926491393595;3996.320625493673;4007.8196481158125;4087.8569250076102;275964;276767;277152;278043;279521;58211.41788582144;60021.399786002454;60167.09749353763;62547;62858.685706571174;65629.35926601778;66948.33051669519;66959;67220;67249.6637516819;12567.93716031414;12784.296863672402;12836.679083022813;12926.67683307906;13059.804102938464;9230.169284764403;9322.347435679438;9323.347365684336;9332.02013788556;9490.430574165572;20174.89912550428;20885.68671469929;20933.26733564318;20945.37163885086;20972.79027209739;7655.693772249159;7731.381489480828;7741.225877412327;7871.960640196762;7961.203879612109;1028.681108856257;1041.442828086985;1070.25617196048;1081.8377243413552;1123.5674514993286;28304.575431368547;28522.714772852007;28810.27974300659;28992;29152.41695166085;933.3046880074388;1043.6764602973103;1106.5020740666732;1143.3387872477597;1143.7369405036889;14356.85643143576;14475.420983160555;14526.491572795112;14702.117872927658;14799.92600036993;324430;328595;329352;329723;329800;52335;53113.468865311625;53180;53354.73322633362;53491.465085348646;1186.3652945674023;1292.6832925933206;1303.4395210059577;1330.933453327349;1333.359987206127;26760.59859102154;27462.725372746016;27684.861575691993;27913.44173116567;27987.160385188476;45591.54408455873;45826;45828.31257531073;45918.770406147756;45944;291396;293840;294405;294989;295308;28346;28362.57456138118;28510.42979140364;28722.425551489272;32610;39075.80462097671;39205.41191882181;39834.800825995684;39838.40242396308;40121;33127.50308745419;34333.82833085819;34502.48246276358;34857;36288.27423451569;35363.823180883934;36464.08839779027;36931;36950.445743313336;37367.25265494729;31039.379212416075;31176.688233118286;31708.524372133976;31721;31735.682643174197;28196.92455357973;29852.850735746182;31251.84374078115;31564.842175788974;31569.526457102704;24242.15152469655;25716.61425078588;25816.8709156453;25899.741002590486;27779 19:17:57 INFO - 2015-11-22 19:17:57,403 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.5227071859068;358.86721912892216;360.06383403151807;362.77145398399006;364.9908752281194;14415.783763243533;14440.494582689607;16473.917630411845;16812;17432.73850892236;10863.239573229857;11129.777404451906;17445;17718.29126834925;17720.46838594841;4829.058333624945;4974;4976.726280054596;4976.87557811054;4983.900321993558;3767.05823544114;3864.7294689371756;4942.406911170665;4979.7261150636705;5332.706701131437;5451.318585176853;5483.561315094803;5496.560275177996;5498.5876059295515;5507.669539827623;5331.680099194042;5472.713912230643;5945.8810823783715;5956.88086238275;5959.910601340984;5236.895262094737;5269.604779641542;5284.2866213061125;5291.312129423189;5295.735213239323;19902.402927912186;20008.69986950197;20243.797562024487;20303.984800759903;20601.278955236496;1951.1219951022097;2013.7684166320914;2086.5201003769216;2089.3731880435807;2211.635080211763;9239.769005774842;9517.143457088827;9620.278479114024;11334.603288884931;12564.994800415945;1762.3215062201145;1767.8939263644222;1772.2822256985942;1802.4772815883377;1830.3227805711838;61350.69324653348;63831.680841595495;64373.67813160951;64602.67698661524;65491;9924.60301587943;10157.136643385395;10224.335418197794;10239.436830974219;10290.331128476626;13341.132826366254;13514.526991555445;13550.661233469045;13569.321533923265;13584.93207533956;6822.590644561342;6880.2431732509385;6952.270011648704;6957.304269573002;7153.606551639606;6352.936470635327;6360.045993100979;6378.2346118466085;6392.296847346788;6408.166938297992;4307.052448461333;4393.231184542756;4453.332000199931;4459.041306119135;4463.062756821039;1702.7360759082362;1737.6003519190656;1747.6242607839376;1774;1785.8303461171167;891.4829398948724;894.5884892949186;899.3299991506369;927.7355953553205;973.8928717841031;15956.68086638284;16836.915815420845;16904.492865214062;17003.82996170047;17231.22459489326;840.8192238668594;917.3303488453333;930.437085563238;934.3179478980246;980.9901900981041;9519.333646644807;9819.214462842956;10078.371786156978;10169.694909152737;10268.691939241835;395464;398657;401322;403306;491979;80796.59601701953;81176;82817.58591207005;83292.58353708313;83658;1144.805383084861;1157.8842115788523;1165.8600967883908;1172.806486929664;1180.6340034589305;14501.854981450051;15505.612359690647;15558.766618500958;15788;15796;31872.203194919384;31889;31994.52008219832;32623.836880815445;32661.34677306488;38445.2310953785;38781.41827872641;39915.01274808802;39996.2000759989;41640.3753943685;9232.169104780602;9325;9346.299027573095;9398;9550.952245239007;13691.520796772065;16956.15219239058;17459.30162793474;17536.56158596045;18146.364877229233;14900.850991489946;14972.850271497582;14999.250037497863;15004.324805383565;15078.39686412575;14265.144091354552;14591.416343346147;14608;14670.266486675837;14990.400383984956;12194.51221951112;13465.192088474723;13928.72142557123;14200.573982780534;15166;9357.298202634693;9451.716448506555;9573.617055317709;9580.233581313618;9599.47202903819;9982.201423886218;10016.1987041038;10746.946265268936;14013.632519031042;14309.141451512942 19:17:57 INFO - 2015-11-22 19:17:57,405 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1298.363801737149;1693.7967443906732;1721.655668866227;1732.887362321449;1751.0369296886709;6655;7078.858422831547;7192.244814294502;7200.35196832285;7205.927940720591;7237.601931893757;7375.18872923978;7401.370883474898;7403.814904627387;7407.333339999399;1988.7016947457853;2019.6768517037276;2021.6462119129167;2275.8293128015425;2297.9540409191804;2289.7366802817637;2324.5583339165505;2325.534913004805;2325.651152327156;2327.092433950752;2242.069541140425;2250.9887450562724;2327.7904988551027;2327.848689835164;2328.685627440291;1971.4085774267662;1995.4013795861256;2235.35174799308;2241.8766967816764;2271.954560908789;2237.7538470768204;2276.863388196697;2279.1225378229337;2280.0651732789643;2287.3709729824263;7509.62451877404;7562.735304264379;7570.432217583648;7574.204708505583;7580.583067931261;4523.185826551219;4584.541545845422;4599.218132917378;4604.815807367701;4621.075784843045;3038.9544156837665;3150.8739650413954;3156.195170231599;3211.727003204707;3216.951745723816;1021.0963297481708;1022.5194158745378;1061.6178175856762;1068.8717353917502;1089.4443833644818;9855.457949812757;9888.95055524725;9906.653267135616;9915.454649994248;9922.851157232648;4016.859409920639;4043.3934909763184;4132.690048246393;4144.274751918402;4152.563980782034;8238.423310368336;8262.917370826335;8263.132371101068;8303.252707256255;8317.251447369645;4507.166174257773;4539.046800171935;4543.091381723669;4545.0909818036525;4547.7498737569085;4144.44050053245;4188.706790524634;4189;4191.3293872980175;4198.328267477189;3362.075429256974;3381.864725410956;3411.181316484026;3423.5207071010077;3430.382531144418;1058.8517607534952;1072.404815327485;1074.59165517103;1077.1867240233573;1081.6646839479786;932.7155217658543;953.580424613168;1297.4550688710765;1344.0591585889915;1345.6770375109902;7202.279772022755;7295.270472952662;7303.926960730431;7308.232635573188;7317.158526769384;448.2894612039888;454.5363928151347;482.6862539349451;490.44579625023493;491.1944411165691;5707.885842283131;5756.33802112754;5775.306963164448;5776.104703770922;5780.53755699543;8890.93308802935;9156.771080723036;9204.079592040876;9204.355695101412;9327;9088.091190880992;9183.908160918305;9183.90816091844;9187.862182067274;9194.402363846462;6930.7227710891;7050.506464547432;7052.047973523611;7129.857402851914;7136.464765142531;6876.621785801629;6914.827129321807;6937.375636192565;6945.548539344926;6946.374826265458;7721.112072111554;7989;7991.92008079935;8100.473469224482;8121.309688676529;8276.875846862182;8340.833183336177;8512;8533.445326053787;8540.74377768668;6233.252009758858;6257.217847769029;6291.339409361951;6311.463525600192;6311.779087731908;7269.200387957426;7388.187299396955;7417.443691723034;7442.292982166686;7442.96278518604;6916.861662766817;7029.683664235019;7051.765940960209;7125.572465652078;7170.713171473083;5718.742656580381;6785.185777706707;6872.312768723088;6896;7025.718971241093;6700.128983232149;6948.6178260196175;6955.374016338554;6980.127484064492;6980.127484064492;6399.55203135776;6431.035344698333;6436.9034464484;6442.387973142544;6444.097826304415;6433.035044743326;6465.935340646533;6502.837429064122;6511.413972742476;6523.510736694672 19:17:57 INFO - 2015-11-22 19:17:57,408 DEBUG : BROWSER_OUTPUT: |4;prototype.html;346.979181249125;348.7938787171142;350.86491700695257;352.55754028693985;354.6683850599688;5901.645901245923;6022.397760223976;6033.276006879175;6061.66660833654;6094.573379863411;5729.0833466645345;5739.626924249931;5885.470307672309;5949.732262048207;5960.672163031031;4558.452985641711;4581.633469322446;4597.770111494428;4612.215923293048;4619.2609182530805;4581.083783243348;4599.126166028444;4608.308753686957;4611.469680986679;4620.5148459411785;4375.4311939447825;4441.555844415565;4471.575200355977;4518.118966801475;4523.389342438778;4551;4552.339910712944;4554.658400619966;4555.542226487525;4571.954280457203;4007.31875581149;4007.83968641254;4014.9197016059807;4018.136100738326;4020.2562525932794;5679.602427830054;5685.289338832646;5685.658860468344;5687.146927960797;5690.743916523725;5143.819966301162;5268.420473747885;5283.9471605284225;5289.894202115975;5293;6342.5243106767175;6350.237971443456;6354.904676429857;6358.809235722936;6360.809175724736;4364.886953826785;4372.5627437256335;4375.584319489643;4376.715513491613;4379.956200437987;6478.74085036598;6537.93462065378;6601.075849381091;6619;6640.767573134917;4514.480834704051;4527.3435351873795;4527.615152712056;4528.230200865861;4535.024969631545;4587.311903214544;4588.220002599566;4594.19601569724;4594.19601569724;4594.356790049395;5292.788288468495;5295.629305948623;5300.3639563252655;5300.390455097636;5311.362636483646;6274.372562743689;6397.10440538325;6404.391582799627;6413.230412350455;6414.1340918976275;1611.935522579107;1625.7398816189352;1626.4795265514922;1627.031916009972;1627.8116974608365;1071.132382769959;1076.3918386111977;1079.681493959284;1093.103655002906;1101.5263436722287;6135.9079613805825;6146.846328841814;6147.938520614826;6152.538559608047;6154.107654390088;6591.242007169141;6601.075849380994;6604.900926486107;6609.405153536109;6615.140031795932;5973.402659734079;6041.486473649789;6072.939270607326;6125.479334256638;6217.098520714471;3549.982250088733;3577.8032208228797;3584.5698516178227;3586.5158203642695;3589.2462582857893;6867.896981545281;6873.759418420332;6875.243723190445;6876.346747059021;6879.105716256855;6651.534392592472;6826.931730682729;6827.829304267334;6831.28271531492;6832.145981752281;6854.34883686049;6861.279565645636;6863.862722745517;6864.897026544606;6882.8967565486555;6518.445932095825;6632.767853125118;6641.867162656817;6648.102506161799;6648.401643851883;6566.9343306566325;6580.177477815273;6584.604923704593;6586.736530538725;6589.044588534732;6858.6227757471825;6860.176778786577;6861.656917154022;6866.073080134118;6866.34769696878;4538.841140560065;4539.273716205391;4540.614047805973;4543;4545.772711364485;6491.545591808717;6597.406233439014;6621.443960669335;6631.33686631333;6632.668366581555;6384.138141350858;6436.806895793134;6443.838904027437;6444.871102578016;6449.903516402129;6327.114204011534;6430.164078669743;6435.517336199898;6439.903401449075;6444;6252.406021427957;6282.120503129657;6293.968530157503;6314.116023756769;6315.957866951901;6431.710573024132;6438.5171112165835;6444.516661250519;6445.967770161119;6450.709718062604;6184.6598437086395;6289.49684025286;6298.149749783721;6299;6304.779332723333 19:17:57 INFO - 2015-11-22 19:17:57,409 DEBUG : BROWSER_OUTPUT: |5;yui.html;733.2191216354582;775.5308038636629;793.4247670438932;793.8610743119953;888.4536010353632;13256.536021239246;13413.262270575115;13475.528356507511;13483.595492135228;13492.460301587924;12372.257664540133;12524.812127818068;12603.495860165582;12612.810807837866;13540.729185416285;809.9109097999216;832.4464231286188;834.4742812028413;834.837206744685;835.0230230630177;819.319964429525;842.7008412013708;845.3364109174299;846.3652260804397;846.4582667093065;868.7524055644143;884.6196135661663;884.8716936044269;885.4333226734863;886.1271647427311;874.5583480342418;879.2131042716765;881.7619242804407;882.5851849630695;882.6910581296559;635.9268684101295;643.7392855893371;645.7578408096964;647.2459584583954;647.488484097567;12600.432980515807;12789.232646041268;12799.360031998363;12800.103992720513;12808.10343275971;10757.78484430307;11003.889961100447;11142.721431964266;11349.09207263417;11350.829737553944;9867.40795552269;9944.105030547285;9984.800303993952;10099.89900100997;10143.34068285559;3713.8885833425043;3718.8326525306425;3757.7933213673236;3797.525309336333;3864.3623802072625;41623.37564936528;44083.338749918774;48006.27990580144;50140.749296253285;50288;9845.704628861145;10030.253434508666;10081.49592520371;10132.24008199388;10226.23303252259;17914.731279030824;17968.640627187382;17973.550661233574;17979.910100449677;18004.63990720178;7231.493795434374;7514.887276690854;7614.086309642767;7677.30904218623;7773.689052437952;4171.687123465752;4172.645325147336;4185.727927684691;4194.370844373308;4219.810108545123;3258.885938992124;3295.8187299698266;3297.9175520612175;3298.868045278162;3299.538064670948;590.5659340384814;601.1133577972543;605.4157737783061;620.314552419579;629.8456878064903;664.7606861529846;671.8891382921861;680.9693563789641;691.5781373362245;718.9352958233679;7952.1205323911045;8315.667373305;8369.874451883226;8396.244338009486;8448.07071222165;674.3896773420063;682.7234969837224;703.4021082080191;707.4163814852665;717.1716667249335;4105.281575724236;4113.670906327487;4234.301340278819;4256.3189889617515;4298.41971333866;58044.4195558039;58146.60387357388;58686.4131358681;58783;58969.705151474824;44454.333185001604;45914;50561.74719126454;51775.48224517782;51823.74088129611;37819.62180378216;46608.30087548689;48925.5107448921;49143.754281228365;49671.50328496741;8610.354223433393;8625.69810056645;8796.47221166732;8990.415622984485;9427.104425079606;32951;33297;34955.47566786551;35271.823640882656;35649;43137.56862431461;44995.32507012463;45043.099138017715;48131.278030828864;48597.27104093371;10792.838107428552;10805.621803236849;11303.321800691985;11531.423428828693;11650.359230242419;12549.74900502003;12616.87383126157;12656.810147847606;12679.556215532411;12776.936115319735;11276;11449.42752862337;11486.425678715863;11570.479328430409;11956.282623042645;11970.58102966392;12212.32832194238;12257.816132757838;12371.257724536557;12739.044571656977;11798.705032374259;12047.096467765128;12168.878311216775;12257.570985015482;12362.258264504158;9198.2181514572;9472.810543788948;9887.456189909624;10359.896401035892;10512.789744205225;8982.86525702128;8992.235659968976;9381;9971.152452041368;10045.849312260469 19:17:57 INFO - 2015-11-22 19:17:57,409 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:17:57 INFO - 2015-11-22 19:17:57,409 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:17:57 INFO - 2015-11-22 19:17:57,409 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,874 19:17:57 INFO - 2015-11-22 19:17:57,409 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:17:57 INFO - 2015-11-22 19:17:57,410 DEBUG : BROWSER_OUTPUT: __startTimestamp1448248677396__endTimestamp 19:17:57 INFO - 2015-11-22 19:17:57,410 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,410 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:17:57 INFO - 2015-11-22 19:17:57,410 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:17:57 INFO - 2015-11-22 19:17:57,410 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,410 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5490.09 Median:5687.15 stddev:610.29 (10.7%) stddev-sans-first:585.19 19:17:57 INFO - 2015-11-22 19:17:57,411 DEBUG : BROWSER_OUTPUT: Values: 3203.2 3248.9 3294.9 3303.2 3322.6 5284.9 5387.9 5465.3 5471.4 5497.4 5423.0 5472.0 5489.8 5492.0 5551.0 4979.6 5002.3 5026.8 5027.8 5056.5 4923.1 5086.9 5088.8 5142.0 5148.2 4972.5 4981.1 5080.1 5092.8 5101.0 5062.2 5062.7 5067.6 5078.7 5146.1 5011.9 5056.5 5073.5 5082.8 5088.9 5146.6 5212.5 5252.8 5346.9 5420.1 5922.5 5953.5 5959.6 5970.5 5972.5 6005.9 6011.0 6015.3 6018.8 6019.5 5363.1 5454.9 5458.6 5466.9 5591.8 6057.5 6069.8 6072.6 6075.1 6085.5 5807.5 5815.6 5863.2 5868.9 5877.6 5671.8 5774.0 5831.8 5904.0 5954.4 5854.5 5857.7 5865.2 5870.6 5893.6 5687.1 5870.1 5881.0 5883.1 5889.0 4493.7 4493.8 4557.9 4576.5 4599.7 4035.4 4036.0 4041.5 4079.0 4081.8 5430.4 5577.6 5581.4 5584.8 5588.1 5777.6 5803.8 5853.6 5864.8 5917.0 5500.8 5504.1 5573.1 5587.8 5632.0 5141.5 5154.6 5161.2 5164.7 5200.1 5900.4 6007.6 6014.4 6124.3 6136.0 5914.6 5961.0 5981.3 6032.8 6035.2 6005.7 6069.4 6072.9 6088.3 6102.4 5791.3 5826.5 5880.6 5881.0 5883.1 5911.4 5920.1 5931.9 5949.9 5995.7 5754.6 5843.4 5845.0 5850.6 5851.3 5655.1 5807.4 5812.7 5821.2 5822.4 5602.3 5602.9 5659.4 5666.4 5702.1 5875.3 5894.7 5932.4 5942.1 5980.8 5608.4 5708.8 5803.2 5805.1 5812.2 19:17:57 INFO - 2015-11-22 19:17:57,412 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,412 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46899.67 Median:31728.34 stddev:78699.23 (248.0%) stddev-sans-first:78843.08 19:17:57 INFO - 2015-11-22 19:17:57,412 DEBUG : BROWSER_OUTPUT: Values: 557.6 593.8 597.8 599.8 600.1 29964.6 30577.1 40754.4 40986.4 41014.6 43811.0 44136.6 44150.0 44173.0 44359.0 7609.1 8757.7 8922.5 8930.9 8932.8 7784.1 7824.0 7879.3 7880.8 10586.9 10498.4 10566.4 10572.6 10590.9 10593.1 10418.5 10471.3 10506.0 10508.6 10514.4 10308.9 10409.7 10437.1 10438.3 10445.1 40230.4 40523.8 40653.2 40659.8 40699.8 3083.9 3100.7 3143.6 3148.9 3215.3 30485.5 30790.0 31762.8 31905.0 32273.2 3864.2 3918.4 3996.3 4007.8 4087.9 275964.0 276767.0 277152.0 278043.0 279521.0 58211.4 60021.4 60167.1 62547.0 62858.7 65629.4 66948.3 66959.0 67220.0 67249.7 12567.9 12784.3 12836.7 12926.7 13059.8 9230.2 9322.3 9323.3 9332.0 9490.4 20174.9 20885.7 20933.3 20945.4 20972.8 7655.7 7731.4 7741.2 7872.0 7961.2 1028.7 1041.4 1070.3 1081.8 1123.6 28304.6 28522.7 28810.3 28992.0 29152.4 933.3 1043.7 1106.5 1143.3 1143.7 14356.9 14475.4 14526.5 14702.1 14799.9 324430.0 328595.0 329352.0 329723.0 329800.0 52335.0 53113.5 53180.0 53354.7 53491.5 1186.4 1292.7 1303.4 1330.9 1333.4 26760.6 27462.7 27684.9 27913.4 27987.2 45591.5 45826.0 45828.3 45918.8 45944.0 291396.0 293840.0 294405.0 294989.0 295308.0 28346.0 28362.6 28510.4 28722.4 32610.0 39075.8 39205.4 39834.8 39838.4 40121.0 33127.5 34333.8 34502.5 34857.0 36288.3 35363.8 36464.1 36931.0 36950.4 37367.3 31039.4 31176.7 31708.5 31721.0 31735.7 28196.9 29852.9 31251.8 31564.8 31569.5 24242.2 25716.6 25816.9 25899.7 27779.0 19:17:57 INFO - 2015-11-22 19:17:57,412 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,413 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25205.92 Median:361.42 stddev:68959.33 (19080.2%) stddev-sans-first:69127.54 19:17:57 INFO - 2015-11-22 19:17:57,414 DEBUG : BROWSER_OUTPUT: Values: 357.5 358.9 360.1 362.8 365.0 14415.8 14440.5 16473.9 16812.0 17432.7 10863.2 11129.8 17445.0 17718.3 17720.5 4829.1 4974.0 4976.7 4976.9 4983.9 3767.1 3864.7 4942.4 4979.7 5332.7 5451.3 5483.6 5496.6 5498.6 5507.7 5331.7 5472.7 5945.9 5956.9 5959.9 5236.9 5269.6 5284.3 5291.3 5295.7 19902.4 20008.7 20243.8 20304.0 20601.3 1951.1 2013.8 2086.5 2089.4 2211.6 9239.8 9517.1 9620.3 11334.6 12565.0 1762.3 1767.9 1772.3 1802.5 1830.3 61350.7 63831.7 64373.7 64602.7 65491.0 9924.6 10157.1 10224.3 10239.4 10290.3 13341.1 13514.5 13550.7 13569.3 13584.9 6822.6 6880.2 6952.3 6957.3 7153.6 6352.9 6360.0 6378.2 6392.3 6408.2 4307.1 4393.2 4453.3 4459.0 4463.1 1702.7 1737.6 1747.6 1774.0 1785.8 891.5 894.6 899.3 927.7 973.9 15956.7 16836.9 16904.5 17003.8 17231.2 840.8 917.3 930.4 934.3 981.0 9519.3 9819.2 10078.4 10169.7 10268.7 395464.0 398657.0 401322.0 403306.0 491979.0 80796.6 81176.0 82817.6 83292.6 83658.0 1144.8 1157.9 1165.9 1172.8 1180.6 14501.9 15505.6 15558.8 15788.0 15796.0 31872.2 31889.0 31994.5 32623.8 32661.3 38445.2 38781.4 39915.0 39996.2 41640.4 9232.2 9325.0 9346.3 9398.0 9551.0 13691.5 16956.2 17459.3 17536.6 18146.4 14900.9 14972.9 14999.3 15004.3 15078.4 14265.1 14591.4 14608.0 14670.3 14990.4 12194.5 13465.2 13928.7 14200.6 15166.0 9357.3 9451.7 9573.6 9580.2 9599.5 9982.2 10016.2 10746.9 14013.6 14309.1 19:17:57 INFO - 2015-11-22 19:17:57,414 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,414 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5269.18 Median:6432.04 stddev:2715.93 (42.2%) stddev-sans-first:2707.15 19:17:57 INFO - 2015-11-22 19:17:57,414 DEBUG : BROWSER_OUTPUT: Values: 1298.4 1693.8 1721.7 1732.9 1751.0 6655.0 7078.9 7192.2 7200.4 7205.9 7237.6 7375.2 7401.4 7403.8 7407.3 1988.7 2019.7 2021.6 2275.8 2298.0 2289.7 2324.6 2325.5 2325.7 2327.1 2242.1 2251.0 2327.8 2327.8 2328.7 1971.4 1995.4 2235.4 2241.9 2272.0 2237.8 2276.9 2279.1 2280.1 2287.4 7509.6 7562.7 7570.4 7574.2 7580.6 4523.2 4584.5 4599.2 4604.8 4621.1 3039.0 3150.9 3156.2 3211.7 3217.0 1021.1 1022.5 1061.6 1068.9 1089.4 9855.5 9889.0 9906.7 9915.5 9922.9 4016.9 4043.4 4132.7 4144.3 4152.6 8238.4 8262.9 8263.1 8303.3 8317.3 4507.2 4539.0 4543.1 4545.1 4547.7 4144.4 4188.7 4189.0 4191.3 4198.3 3362.1 3381.9 3411.2 3423.5 3430.4 1058.9 1072.4 1074.6 1077.2 1081.7 932.7 953.6 1297.5 1344.1 1345.7 7202.3 7295.3 7303.9 7308.2 7317.2 448.3 454.5 482.7 490.4 491.2 5707.9 5756.3 5775.3 5776.1 5780.5 8890.9 9156.8 9204.1 9204.4 9327.0 9088.1 9183.9 9183.9 9187.9 9194.4 6930.7 7050.5 7052.0 7129.9 7136.5 6876.6 6914.8 6937.4 6945.5 6946.4 7721.1 7989.0 7991.9 8100.5 8121.3 8276.9 8340.8 8512.0 8533.4 8540.7 6233.3 6257.2 6291.3 6311.5 6311.8 7269.2 7388.2 7417.4 7442.3 7443.0 6916.9 7029.7 7051.8 7125.6 7170.7 5718.7 6785.2 6872.3 6896.0 7025.7 6700.1 6948.6 6955.4 6980.1 6980.1 6399.6 6431.0 6436.9 6442.4 6444.1 6433.0 6465.9 6502.8 6511.4 6523.5 19:17:57 INFO - 2015-11-22 19:17:57,415 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,415 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5339.36 Median:6067.30 stddev:1616.12 (26.6%) stddev-sans-first:1576.62 19:17:57 INFO - 2015-11-22 19:17:57,415 DEBUG : BROWSER_OUTPUT: Values: 347.0 348.8 350.9 352.6 354.7 5901.6 6022.4 6033.3 6061.7 6094.6 5729.1 5739.6 5885.5 5949.7 5960.7 4558.5 4581.6 4597.8 4612.2 4619.3 4581.1 4599.1 4608.3 4611.5 4620.5 4375.4 4441.6 4471.6 4518.1 4523.4 4551.0 4552.3 4554.7 4555.5 4572.0 4007.3 4007.8 4014.9 4018.1 4020.3 5679.6 5685.3 5685.7 5687.1 5690.7 5143.8 5268.4 5283.9 5289.9 5293.0 6342.5 6350.2 6354.9 6358.8 6360.8 4364.9 4372.6 4375.6 4376.7 4380.0 6478.7 6537.9 6601.1 6619.0 6640.8 4514.5 4527.3 4527.6 4528.2 4535.0 4587.3 4588.2 4594.2 4594.2 4594.4 5292.8 5295.6 5300.4 5300.4 5311.4 6274.4 6397.1 6404.4 6413.2 6414.1 1611.9 1625.7 1626.5 1627.0 1627.8 1071.1 1076.4 1079.7 1093.1 1101.5 6135.9 6146.8 6147.9 6152.5 6154.1 6591.2 6601.1 6604.9 6609.4 6615.1 5973.4 6041.5 6072.9 6125.5 6217.1 3550.0 3577.8 3584.6 3586.5 3589.2 6867.9 6873.8 6875.2 6876.3 6879.1 6651.5 6826.9 6827.8 6831.3 6832.1 6854.3 6861.3 6863.9 6864.9 6882.9 6518.4 6632.8 6641.9 6648.1 6648.4 6566.9 6580.2 6584.6 6586.7 6589.0 6858.6 6860.2 6861.7 6866.1 6866.3 4538.8 4539.3 4540.6 4543.0 4545.8 6491.5 6597.4 6621.4 6631.3 6632.7 6384.1 6436.8 6443.8 6444.9 6449.9 6327.1 6430.2 6435.5 6439.9 6444.0 6252.4 6282.1 6294.0 6314.1 6316.0 6431.7 6438.5 6444.5 6446.0 6450.7 6184.7 6289.5 6298.1 6299.0 6304.8 19:17:57 INFO - 2015-11-22 19:17:57,415 DEBUG : BROWSER_OUTPUT: 19:17:57 INFO - 2015-11-22 19:17:57,415 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13762.70 Median:45478.55 stddev:15876.22 (34.9%) stddev-sans-first:15890.60 19:17:57 INFO - 2015-11-22 19:17:57,416 DEBUG : BROWSER_OUTPUT: Values: 733.2 775.5 793.4 793.9 888.5 13256.5 13413.3 13475.5 13483.6 13492.5 12372.3 12524.8 12603.5 12612.8 13540.7 809.9 832.4 834.5 834.8 835.0 819.3 842.7 845.3 846.4 846.5 868.8 884.6 884.9 885.4 886.1 874.6 879.2 881.8 882.6 882.7 635.9 643.7 645.8 647.2 647.5 12600.4 12789.2 12799.4 12800.1 12808.1 10757.8 11003.9 11142.7 11349.1 11350.8 9867.4 9944.1 9984.8 10099.9 10143.3 3713.9 3718.8 3757.8 3797.5 3864.4 41623.4 44083.3 48006.3 50140.7 50288.0 9845.7 10030.3 10081.5 10132.2 10226.2 17914.7 17968.6 17973.6 17979.9 18004.6 7231.5 7514.9 7614.1 7677.3 7773.7 4171.7 4172.6 4185.7 4194.4 4219.8 3258.9 3295.8 3297.9 3298.9 3299.5 590.6 601.1 605.4 620.3 629.8 664.8 671.9 681.0 691.6 718.9 7952.1 8315.7 8369.9 8396.2 8448.1 674.4 682.7 703.4 707.4 717.2 4105.3 4113.7 4234.3 4256.3 4298.4 58044.4 58146.6 58686.4 58783.0 58969.7 44454.3 45914.0 50561.7 51775.5 51823.7 37819.6 46608.3 48925.5 49143.8 49671.5 8610.4 8625.7 8796.5 8990.4 9427.1 32951.0 33297.0 34955.5 35271.8 35649.0 43137.6 44995.3 45043.1 48131.3 48597.3 10792.8 10805.6 11303.3 11531.4 11650.4 12549.7 12616.9 12656.8 12679.6 12776.9 11276.0 11449.4 11486.4 11570.5 11956.3 11970.6 12212.3 12257.8 12371.3 12739.0 11798.7 12047.1 12168.9 12257.6 12362.3 9198.2 9472.8 9887.5 10359.9 10512.8 8982.9 8992.2 9381.0 9971.2 10045.8 19:17:57 INFO - 2015-11-22 19:17:57,416 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:17:57 INFO - 2015-11-22 19:17:57,416 DEBUG : BROWSER_OUTPUT: 19:17:58 INFO - 2015-11-22 19:17:58,145 INFO : Browser exited with error code: 0 19:17:58 INFO - 2015-11-22 19:17:58,162 INFO : Completed test dromaeo_css (00:18:33) 19:17:58 INFO - 2015-11-22 19:17:58,162 INFO : Starting test kraken 19:17:58 INFO - 2015-11-22 19:17:58,162 DEBUG : operating with platform_type : linux_ 19:17:58 INFO - 2015-11-22 19:17:58,162 INFO : Initialising browser for kraken test... 19:17:58 INFO - 2015-11-22 19:17:58,168 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpa1TR6U/profile http://localhost:48008/getInfo.html 19:18:02 INFO - 2015-11-22 19:18:02,172 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:18:02 INFO - 2015-11-22 19:18:02,173 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:18:02 INFO - 2015-11-22 19:18:02,173 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:18:02 INFO - 2015-11-22 19:18:02,173 DEBUG : BROWSER_OUTPUT: __metrics 19:18:02 INFO - 2015-11-22 19:18:02,233 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:18:03 INFO - 2015-11-22 19:18:03,588 INFO : Browser initialized. 19:18:03 INFO - 2015-11-22 19:18:03,588 INFO : Running cycle 1/1 for kraken test... 19:18:03 INFO - 2015-11-22 19:18:03,588 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpa1TR6U/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:18:15 INFO - 2015-11-22 19:18:15,345 DEBUG : BROWSER_OUTPUT: RSS: Main: 142422016 19:18:15 INFO - 2015-11-22 19:18:15,345 DEBUG : BROWSER_OUTPUT: 19:18:17 INFO - 2015-11-22 19:18:17,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48008/tests/kraken/driver.html?testName=audio-beat-detection) 19:18:18 INFO - 2015-11-22 19:18:18,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 177242112 19:18:18 INFO - 2015-11-22 19:18:18,414 DEBUG : BROWSER_OUTPUT: 19:18:21 INFO - 2015-11-22 19:18:21,012 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48008/tests/kraken/driver.html?testName=audio-dft) 19:18:21 INFO - 2015-11-22 19:18:21,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 166674432 19:18:21 INFO - 2015-11-22 19:18:21,579 DEBUG : BROWSER_OUTPUT: 19:18:24 INFO - 2015-11-22 19:18:24,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48008/tests/kraken/driver.html?testName=audio-fft) 19:18:24 INFO - 2015-11-22 19:18:24,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 151552000 19:18:24 INFO - 2015-11-22 19:18:24,984 DEBUG : BROWSER_OUTPUT: 19:18:26 INFO - 2015-11-22 19:18:26,969 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48008/tests/kraken/driver.html?testName=audio-oscillator) 19:18:27 INFO - 2015-11-22 19:18:27,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 152625152 19:18:27 INFO - 2015-11-22 19:18:27,543 DEBUG : BROWSER_OUTPUT: 19:18:29 INFO - 2015-11-22 19:18:29,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48008/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:18:30 INFO - 2015-11-22 19:18:30,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 155721728 19:18:30 INFO - 2015-11-22 19:18:30,262 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2015-11-22 19:18:33,168 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48008/tests/kraken/driver.html?testName=imaging-darkroom) 19:18:33 INFO - 2015-11-22 19:18:33,734 DEBUG : BROWSER_OUTPUT: RSS: Main: 154734592 19:18:33 INFO - 2015-11-22 19:18:33,735 DEBUG : BROWSER_OUTPUT: 19:18:37 INFO - 2015-11-22 19:18:37,108 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48008/tests/kraken/driver.html?testName=imaging-desaturate) 19:18:37 INFO - 2015-11-22 19:18:37,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 156377088 19:18:37 INFO - 2015-11-22 19:18:37,681 DEBUG : BROWSER_OUTPUT: 19:18:40 INFO - 2015-11-22 19:18:40,418 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48008/tests/kraken/driver.html?testName=json-parse-financial) 19:18:41 INFO - 2015-11-22 19:18:40,999 DEBUG : BROWSER_OUTPUT: RSS: Main: 158015488 19:18:41 INFO - 2015-11-22 19:18:41,000 DEBUG : BROWSER_OUTPUT: 19:18:42 INFO - 2015-11-22 19:18:42,535 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48008/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:18:43 INFO - 2015-11-22 19:18:43,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 166305792 19:18:43 INFO - 2015-11-22 19:18:43,098 DEBUG : BROWSER_OUTPUT: 19:18:44 INFO - 2015-11-22 19:18:44,893 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:18:45 INFO - 2015-11-22 19:18:45,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 164020224 19:18:45 INFO - 2015-11-22 19:18:45,496 DEBUG : BROWSER_OUTPUT: 19:18:47 INFO - 2015-11-22 19:18:47,572 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:18:48 INFO - 2015-11-22 19:18:48,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 172969984 19:18:48 INFO - 2015-11-22 19:18:48,155 DEBUG : BROWSER_OUTPUT: 19:18:50 INFO - 2015-11-22 19:18:50,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:18:51 INFO - 2015-11-22 19:18:51,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 181002240 19:18:51 INFO - 2015-11-22 19:18:51,117 DEBUG : BROWSER_OUTPUT: 19:18:53 INFO - 2015-11-22 19:18:53,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:18:54 INFO - 2015-11-22 19:18:54,239 DEBUG : BROWSER_OUTPUT: RSS: Main: 179544064 19:18:54 INFO - 2015-11-22 19:18:54,239 DEBUG : BROWSER_OUTPUT: 19:18:55 INFO - 2015-11-22 19:18:55,912 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48008/tests/kraken/driver.html?testName=ai-astar) 19:18:56 INFO - 2015-11-22 19:18:56,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 237404160 19:18:56 INFO - 2015-11-22 19:18:56,163 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,164 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:18:56 INFO - 2015-11-22 19:18:56,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:18:56 INFO - 2015-11-22 19:18:56,164 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:18:56 INFO - 2015-11-22 19:18:56,164 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;105;110;111;111;97;110;111;110 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;124;152;160;125;152;151;123;170;128 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;159;162;160;159;165;160;159 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;82;95;71;73;72;106;92;72 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;134;90;90;90;136;90;91;135 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;114;109;109;109;110;109;109;109;109;109 19:18:56 INFO - 2015-11-22 19:18:56,165 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;146;146;146;146;147;145;146 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;96;96;96;96;95;95;95 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;56;59;56;57;57;56;66;54;55 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;67;68;68;68;69;68;69;67;68 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;84;86;85;93;85;86;84;88;86 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;120;113;114;120;117;123;124;123;122 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;150;151;146;152;141;152;151;150;147 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;66;68;67;67;69;66;67;68;66 19:18:56 INFO - 2015-11-22 19:18:56,166 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:18:56 INFO - 2015-11-22 19:18:56,167 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:18:56 INFO - 2015-11-22 19:18:56,167 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,916 19:18:56 INFO - 2015-11-22 19:18:56,167 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:18:56 INFO - 2015-11-22 19:18:56,167 DEBUG : BROWSER_OUTPUT: __startTimestamp1448248736163__endTimestamp 19:18:56 INFO - 2015-11-22 19:18:56,167 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,171 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:18:56 INFO - 2015-11-22 19:18:56,171 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:18:56 INFO - 2015-11-22 19:18:56,171 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,172 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.50 Median:110.50 stddev:4.40 (4.0%) stddev-sans-first:4.64 19:18:56 INFO - 2015-11-22 19:18:56,172 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 105.0 110.0 111.0 111.0 97.0 110.0 111.0 110.0 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.50 Median:151.50 stddev:17.00 (11.2%) stddev-sans-first:17.87 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: Values: 150.0 124.0 152.0 160.0 125.0 152.0 151.0 123.0 170.0 128.0 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:160.00 stddev:1.84 (1.2%) stddev-sans-first:1.94 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 159.0 162.0 160.0 159.0 165.0 160.0 159.0 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.60 Median:72.50 stddev:12.69 (17.5%) stddev-sans-first:12.97 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 82.0 95.0 71.0 73.0 72.0 106.0 92.0 72.0 19:18:56 INFO - 2015-11-22 19:18:56,173 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.40 Median:90.00 stddev:21.82 (24.2%) stddev-sans-first:22.51 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 134.0 90.0 90.0 90.0 136.0 90.0 91.0 135.0 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:109.00 stddev:1.58 (1.4%) stddev-sans-first:0.33 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: Values: 114.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,174 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.00 stddev:3.34 (5.9%) stddev-sans-first:3.54 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: Values: 58.0 56.0 59.0 56.0 57.0 57.0 56.0 66.0 54.0 55.0 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 68.0 68.0 68.0 69.0 68.0 69.0 67.0 68.0 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:2.96 (3.4%) stddev-sans-first:2.78 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: Values: 82.0 84.0 86.0 85.0 93.0 85.0 86.0 84.0 88.0 86.0 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.20 Median:121.00 stddev:3.97 (3.3%) stddev-sans-first:4.03 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: Values: 116.0 120.0 113.0 114.0 120.0 117.0 123.0 124.0 123.0 122.0 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.20 Median:150.50 stddev:4.05 (2.7%) stddev-sans-first:3.62 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: Values: 142.0 150.0 151.0 146.0 152.0 141.0 152.0 151.0 150.0 147.0 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,175 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.16 (1.7%) stddev-sans-first:1.05 19:18:56 INFO - 2015-11-22 19:18:56,176 DEBUG : BROWSER_OUTPUT: Values: 69.0 66.0 68.0 67.0 67.0 69.0 66.0 67.0 68.0 66.0 19:18:56 INFO - 2015-11-22 19:18:56,176 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:18:56 INFO - 2015-11-22 19:18:56,176 DEBUG : BROWSER_OUTPUT: 19:18:56 INFO - 2015-11-22 19:18:56,977 INFO : Browser exited with error code: 0 19:18:57 INFO - 2015-11-22 19:18:57,028 INFO : Completed test kraken (00:00:58) 19:18:57 INFO - 2015-11-22 19:18:57,028 INFO : Starting test v8_7 19:18:57 INFO - 2015-11-22 19:18:57,028 DEBUG : operating with platform_type : linux_ 19:18:57 INFO - 2015-11-22 19:18:57,029 INFO : Initialising browser for v8_7 test... 19:18:57 INFO - 2015-11-22 19:18:57,035 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGm3hGj/profile http://localhost:48008/getInfo.html 19:19:00 INFO - 2015-11-22 19:19:00,807 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:19:00 INFO - 2015-11-22 19:19:00,807 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:19:00 INFO - 2015-11-22 19:19:00,807 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:19:00 INFO - 2015-11-22 19:19:00,807 DEBUG : BROWSER_OUTPUT: __metrics 19:19:00 INFO - 2015-11-22 19:19:00,883 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:02 INFO - 2015-11-22 19:19:02,153 INFO : Browser initialized. 19:19:02 INFO - 2015-11-22 19:19:02,154 INFO : Running cycle 1/1 for v8_7 test... 19:19:02 INFO - 2015-11-22 19:19:02,154 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGm3hGj/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:19:13 INFO - 2015-11-22 19:19:13,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 142057472 19:19:13 INFO - 2015-11-22 19:19:13,945 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,356 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48008/tests/v8_7/run.html (next: http://localhost:48008/tests/v8_7/run.html) 19:19:35 INFO - 2015-11-22 19:19:35,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 522006528 19:19:35 INFO - 2015-11-22 19:19:35,600 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: |0;Richards;162.94606485253382 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.28845791752966 19:19:35 INFO - 2015-11-22 19:19:35,602 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.97780275358247 19:19:35 INFO - 2015-11-22 19:19:35,603 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5025.125628140703 19:19:35 INFO - 2015-11-22 19:19:35,603 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1962.7450980392157 19:19:35 INFO - 2015-11-22 19:19:35,603 DEBUG : BROWSER_OUTPUT: |5;Earley;775.1937984496124 19:19:35 INFO - 2015-11-22 19:19:35,603 DEBUG : BROWSER_OUTPUT: |6;Boyer;12345.67901234568 19:19:35 INFO - 2015-11-22 19:19:35,603 DEBUG : BROWSER_OUTPUT: |7;RegExp;26473.684210526317 19:19:35 INFO - 2015-11-22 19:19:35,604 DEBUG : BROWSER_OUTPUT: |8;Splay;593.1198102016607 19:19:35 INFO - 2015-11-22 19:19:35,604 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 19:19:35 INFO - 2015-11-22 19:19:35,604 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:19:35 INFO - 2015-11-22 19:19:35,604 DEBUG : BROWSER_OUTPUT: __startTimestamp1448248775600__endTimestamp 19:19:35 INFO - 2015-11-22 19:19:35,604 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.95 Median:162.95 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: Values: 162.9 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.29 Median:188.29 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,605 DEBUG : BROWSER_OUTPUT: Values: 188.3 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.98 Median:280.98 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: Values: 281.0 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5025.13 Median:5025.13 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: Values: 5025.1 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,606 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1962.75 Median:1962.75 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: Values: 1962.7 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:775.19 Median:775.19 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: Values: 775.2 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12345.68 Median:12345.68 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: Values: 12345.7 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26473.68 Median:26473.68 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: Values: 26473.7 19:19:35 INFO - 2015-11-22 19:19:35,607 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.12 Median:593.12 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: Values: 593.1 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: Values: 4830.9 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:19:35 INFO - 2015-11-22 19:19:35,608 DEBUG : BROWSER_OUTPUT: 19:19:36 INFO - 2015-11-22 19:19:36,425 INFO : Browser exited with error code: 0 19:19:36 INFO - 2015-11-22 19:19:36,437 INFO : Completed test v8_7 (00:00:39) 19:19:36 INFO - 2015-11-22 19:19:36,607 INFO : Completed test suite (00:20:12) 19:19:36 INFO - 2015-11-22 19:19:36,608 DEBUG : Working with test: dromaeo_css 19:19:36 INFO - 2015-11-22 19:19:36,608 DEBUG : Generating results file: dromaeo_css 19:19:36 INFO - 2015-11-22 19:19:36,609 DEBUG : Working with test: kraken 19:19:36 INFO - 2015-11-22 19:19:36,609 DEBUG : Generating results file: kraken 19:19:36 INFO - 2015-11-22 19:19:36,609 INFO : javascript benchmark 19:19:36 INFO - 2015-11-22 19:19:36,609 DEBUG : Working with test: v8_7 19:19:36 INFO - 2015-11-22 19:19:36,610 DEBUG : Generating results file: v8_7 19:19:36 INFO - 2015-11-22 19:19:36,610 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:19:36 INFO - 2015-11-22 19:19:36,664 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 19:19:36 INFO - 2015-11-22 19:19:36,664 DEBUG : process_Request line: dromaeo_css 6965.12 graph.html#tests=[[72,201,35]] 19:19:36 INFO - 2015-11-22 19:19:36,664 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:19:36 INFO - 2015-11-22 19:19:36,701 DEBUG : process_Request line: kraken 1493.50 graph.html#tests=[[232,201,35]] 19:19:36 INFO - 2015-11-22 19:19:36,701 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:19:36 INFO - 2015-11-22 19:19:36,736 DEBUG : process_Request line: v8_7 19969.14 graph.html#tests=[[230,201,35]] 19:19:36 INFO - 2015-11-22 19:19:36,738 INFO : javascript benchmark 19:19:36 INFO - 2015-11-22 19:19:36,741 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3203.19920019995, 3248.9025329240103, 3294.9176270593234, 3303.1907182740233, 3322.5846769153854, 5284.947150528494, 5387.946120538803, 5465.316835395571, 5471.425500322463, 5497.367802702691, 5423.0, 5472.015037293286, 5489.807856725006, 5491.99999999999, 5550.972245138779, 4979.55184033436, 5002.349694539723, 5026.773795179208, 5027.798888044474, 5056.494350564951, 4923.138450771118, 5086.931744333694, 5088.77100530477, 5142.04872098663, 5148.150555158394, 4972.453030166696, 4981.1283025470575, 5080.085584594772, 5092.8472145835685, 5101.0, 5062.1900495920845, 5062.670926389773, 5067.6452648314635, 5078.720670363128, 5146.125158722989, 5011.899762004739, 5056.519630635084, 5073.543381095682, 5082.847514574569, 5088.872778180538, 5146.588272938156, 5212.478752124795, 5252.842414727565, 5346.91979620306, 5420.07858664028, 5922.46697797196, 5953.523718102541, 5959.582829201958, 5970.492508136814, 5972.462478376923, 6005.939940600581, 6011.0, 6015.3383127855905, 6018.8495287617725, 6019.548533859935, 5363.141897296453, 5454.945450545523, 5458.563314934796, 5466.863328416742, 5591.832245032656, 6057.515398768088, 6069.787557435468, 6072.574919755663, 6075.058365953284, 6085.452309292096, 5807.477327040587, 5815.621984570989, 5863.237779088777, 5868.941310586925, 5877.617954832922, 5671.773129074873, 5773.971130144322, 5831.79588714393, 5904.025835737141, 5954.374790647006, 5854.473097421167, 5857.677827719516, 5865.20819689345, 5870.589058765845, 5893.5579831512805, 5687.146927960839, 5870.060790273621, 5881.0, 5883.14694369314, 5889.0, 4493.662975276868, 4493.842715504941, 4557.931631025537, 4576.519465456146, 4599.67802253839, 4035.3543433050572, 4036.0313524754433, 4041.515018197835, 4079.0414252650603, 4081.836726530965, 5430.402655707869, 5577.553795696334, 5581.441855814467, 5584.776608935678, 5588.050031494656, 5777.62445441045, 5803.82588522345, 5853.590248682552, 5864.765409383662, 5917.0, 5500.7524661390335, 5504.119340905436, 5573.080441727069, 5587.776488940478, 5632.0, 5141.511556402136, 5154.561862241677, 5161.174212126042, 5164.741762911839, 5200.063988482034, 5900.43945825146, 6007.639541627516, 6014.428629280212, 6124.295705993779, 6136.018237082046, 5914.645121292736, 5960.970195148997, 5981.3420523743225, 6032.819015429544, 6035.155078289131, 6005.699715014144, 6069.393060693896, 6072.90890636632, 6088.299845517822, 6102.359252278447, 5791.276090488689, 5826.504747096545, 5880.588358814842, 5880.970595146997, 5883.058710606282, 5911.408859114054, 5920.141579471038, 5931.851703707442, 5949.910751338647, 5995.670238136944, 5754.597178197486, 5843.415658434123, 5845.0, 5850.64896106235, 5851.297844258716, 5655.066913959316, 5807.448292412214, 5812.651240925558, 5821.155932389865, 5822.359540450631, 5602.299712535933, 5602.943970560243, 5659.434056594308, 5666.433356664301, 5702.116171993348, 5875.265591801025, 5894.734736936763, 5932.436418540232, 5942.078977758454, 5980.790672326448, 5608.38307786135, 5708.771649133988, 5803.216565763568, 5805.10020946754, 5812.18629391894], "unit": "ms", "name": "dojo.html", "value": 5450.2292441804675}, {"lowerIsBetter": false, "replicates": [557.6013150597322, 593.8218534439669, 597.8087012156112, 599.7930713903703, 600.1238192239331, 29964.550531742014, 30577.08268751938, 40754.38868416976, 40986.38520422196, 41014.58985410153, 43811.0, 44136.558634413646, 44150.0, 44173.0, 44359.0, 7609.124950630693, 8757.737267881957, 8922.509261990588, 8930.86603700945, 8932.821343573125, 7784.143744188134, 7823.960880195591, 7879.251471110236, 7880.763577092668, 10586.894131058667, 10498.370097794119, 10566.418846963414, 10572.577096916115, 10590.947045264802, 10593.099586535154, 10418.531166097544, 10471.267011309212, 10506.0, 10508.579656813718, 10514.369137851716, 10308.896911030944, 10409.739756506147, 10437.11284540815, 10438.269321147523, 10445.112165465944, 40230.39654405157, 40523.797381012904, 40653.1869362614, 40659.796701016305, 40699.796501017605, 3083.922901927447, 3100.689931006904, 3143.6070491188602, 3148.8583013764205, 3215.340855124717, 30485.542716859265, 30790.0, 31762.841185794154, 31905.042848714576, 32273.1931701707, 3864.1692036212066, 3918.3926491393595, 3996.320625493673, 4007.8196481158125, 4087.8569250076102, 275964.0, 276767.0, 277152.0, 278043.0, 279521.0, 58211.41788582144, 60021.399786002454, 60167.09749353763, 62547.0, 62858.685706571174, 65629.35926601778, 66948.33051669519, 66959.0, 67220.0, 67249.6637516819, 12567.93716031414, 12784.296863672402, 12836.679083022813, 12926.67683307906, 13059.804102938464, 9230.169284764403, 9322.347435679438, 9323.347365684336, 9332.02013788556, 9490.430574165572, 20174.89912550428, 20885.68671469929, 20933.26733564318, 20945.37163885086, 20972.79027209739, 7655.693772249159, 7731.381489480828, 7741.225877412327, 7871.960640196762, 7961.203879612109, 1028.681108856257, 1041.442828086985, 1070.25617196048, 1081.8377243413552, 1123.5674514993286, 28304.575431368547, 28522.714772852007, 28810.27974300659, 28992.0, 29152.41695166085, 933.3046880074388, 1043.6764602973103, 1106.5020740666732, 1143.3387872477597, 1143.7369405036889, 14356.85643143576, 14475.420983160555, 14526.491572795112, 14702.117872927658, 14799.92600036993, 324430.0, 328595.0, 329352.0, 329723.0, 329800.0, 52335.0, 53113.468865311625, 53180.0, 53354.73322633362, 53491.465085348646, 1186.3652945674023, 1292.6832925933206, 1303.4395210059577, 1330.933453327349, 1333.359987206127, 26760.59859102154, 27462.725372746016, 27684.861575691993, 27913.44173116567, 27987.160385188476, 45591.54408455873, 45826.0, 45828.31257531073, 45918.770406147756, 45944.0, 291396.0, 293840.0, 294405.0, 294989.0, 295308.0, 28346.0, 28362.57456138118, 28510.42979140364, 28722.425551489272, 32610.0, 39075.80462097671, 39205.41191882181, 39834.800825995684, 39838.40242396308, 40121.0, 33127.50308745419, 34333.82833085819, 34502.48246276358, 34857.0, 36288.27423451569, 35363.823180883934, 36464.08839779027, 36931.0, 36950.445743313336, 37367.25265494729, 31039.379212416075, 31176.688233118286, 31708.524372133976, 31721.0, 31735.682643174197, 28196.92455357973, 29852.850735746182, 31251.84374078115, 31564.842175788974, 31569.526457102704, 24242.15152469655, 25716.61425078588, 25816.8709156453, 25899.741002590486, 27779.0], "unit": "ms", "name": "ext.html", "value": 18492.017078987134}, {"lowerIsBetter": false, "replicates": [357.5227071859068, 358.86721912892216, 360.06383403151807, 362.77145398399006, 364.9908752281194, 14415.783763243533, 14440.494582689607, 16473.917630411845, 16812.0, 17432.73850892236, 10863.239573229857, 11129.777404451906, 17445.0, 17718.29126834925, 17720.46838594841, 4829.058333624945, 4974.0, 4976.726280054596, 4976.87557811054, 4983.900321993558, 3767.05823544114, 3864.7294689371756, 4942.406911170665, 4979.7261150636705, 5332.706701131437, 5451.318585176853, 5483.561315094803, 5496.560275177996, 5498.5876059295515, 5507.669539827623, 5331.680099194042, 5472.713912230643, 5945.8810823783715, 5956.88086238275, 5959.910601340984, 5236.895262094737, 5269.604779641542, 5284.2866213061125, 5291.312129423189, 5295.735213239323, 19902.402927912186, 20008.69986950197, 20243.797562024487, 20303.984800759903, 20601.278955236496, 1951.1219951022097, 2013.7684166320914, 2086.5201003769216, 2089.3731880435807, 2211.635080211763, 9239.769005774842, 9517.143457088827, 9620.278479114024, 11334.603288884931, 12564.994800415945, 1762.3215062201145, 1767.8939263644222, 1772.2822256985942, 1802.4772815883377, 1830.3227805711838, 61350.69324653348, 63831.680841595495, 64373.67813160951, 64602.67698661524, 65491.0, 9924.60301587943, 10157.136643385395, 10224.335418197794, 10239.436830974219, 10290.331128476626, 13341.132826366254, 13514.526991555445, 13550.661233469045, 13569.321533923265, 13584.93207533956, 6822.590644561342, 6880.2431732509385, 6952.270011648704, 6957.304269573002, 7153.606551639606, 6352.936470635327, 6360.045993100979, 6378.2346118466085, 6392.296847346788, 6408.166938297992, 4307.052448461333, 4393.231184542756, 4453.332000199931, 4459.041306119135, 4463.062756821039, 1702.7360759082362, 1737.6003519190656, 1747.6242607839376, 1774.0, 1785.8303461171167, 891.4829398948724, 894.5884892949186, 899.3299991506369, 927.7355953553205, 973.8928717841031, 15956.68086638284, 16836.915815420845, 16904.492865214062, 17003.82996170047, 17231.22459489326, 840.8192238668594, 917.3303488453333, 930.437085563238, 934.3179478980246, 980.9901900981041, 9519.333646644807, 9819.214462842956, 10078.371786156978, 10169.694909152737, 10268.691939241835, 395464.0, 398657.0, 401322.0, 403306.0, 491979.0, 80796.59601701953, 81176.0, 82817.58591207005, 83292.58353708313, 83658.0, 1144.805383084861, 1157.8842115788523, 1165.8600967883908, 1172.806486929664, 1180.6340034589305, 14501.854981450051, 15505.612359690647, 15558.766618500958, 15788.0, 15796.0, 31872.203194919384, 31889.0, 31994.52008219832, 32623.836880815445, 32661.34677306488, 38445.2310953785, 38781.41827872641, 39915.01274808802, 39996.2000759989, 41640.3753943685, 9232.169104780602, 9325.0, 9346.299027573095, 9398.0, 9550.952245239007, 13691.520796772065, 16956.15219239058, 17459.30162793474, 17536.56158596045, 18146.364877229233, 14900.850991489946, 14972.850271497582, 14999.250037497863, 15004.324805383565, 15078.39686412575, 14265.144091354552, 14591.416343346147, 14608.0, 14670.266486675837, 14990.400383984956, 12194.51221951112, 13465.192088474723, 13928.72142557123, 14200.573982780534, 15166.0, 9357.298202634693, 9451.716448506555, 9573.617055317709, 9580.233581313618, 9599.47202903819, 9982.201423886218, 10016.1987041038, 10746.946265268936, 14013.632519031042, 14309.141451512942], "unit": "ms", "name": "jquery.html", "value": 8610.057532155211}, {"lowerIsBetter": false, "replicates": [1298.363801737149, 1693.7967443906732, 1721.655668866227, 1732.887362321449, 1751.0369296886709, 6655.0, 7078.858422831547, 7192.244814294502, 7200.35196832285, 7205.927940720591, 7237.601931893757, 7375.18872923978, 7401.370883474898, 7403.814904627387, 7407.333339999399, 1988.7016947457853, 2019.6768517037276, 2021.6462119129167, 2275.8293128015425, 2297.9540409191804, 2289.7366802817637, 2324.5583339165505, 2325.534913004805, 2325.651152327156, 2327.092433950752, 2242.069541140425, 2250.9887450562724, 2327.7904988551027, 2327.848689835164, 2328.685627440291, 1971.4085774267662, 1995.4013795861256, 2235.35174799308, 2241.8766967816764, 2271.954560908789, 2237.7538470768204, 2276.863388196697, 2279.1225378229337, 2280.0651732789643, 2287.3709729824263, 7509.62451877404, 7562.735304264379, 7570.432217583648, 7574.204708505583, 7580.583067931261, 4523.185826551219, 4584.541545845422, 4599.218132917378, 4604.815807367701, 4621.075784843045, 3038.9544156837665, 3150.8739650413954, 3156.195170231599, 3211.727003204707, 3216.951745723816, 1021.0963297481708, 1022.5194158745378, 1061.6178175856762, 1068.8717353917502, 1089.4443833644818, 9855.457949812757, 9888.95055524725, 9906.653267135616, 9915.454649994248, 9922.851157232648, 4016.859409920639, 4043.3934909763184, 4132.690048246393, 4144.274751918402, 4152.563980782034, 8238.423310368336, 8262.917370826335, 8263.132371101068, 8303.252707256255, 8317.251447369645, 4507.166174257773, 4539.046800171935, 4543.091381723669, 4545.0909818036525, 4547.7498737569085, 4144.44050053245, 4188.706790524634, 4189.0, 4191.3293872980175, 4198.328267477189, 3362.075429256974, 3381.864725410956, 3411.181316484026, 3423.5207071010077, 3430.382531144418, 1058.8517607534952, 1072.404815327485, 1074.59165517103, 1077.1867240233573, 1081.6646839479786, 932.7155217658543, 953.580424613168, 1297.4550688710765, 1344.0591585889915, 1345.6770375109902, 7202.279772022755, 7295.270472952662, 7303.926960730431, 7308.232635573188, 7317.158526769384, 448.2894612039888, 454.5363928151347, 482.6862539349451, 490.44579625023493, 491.1944411165691, 5707.885842283131, 5756.33802112754, 5775.306963164448, 5776.104703770922, 5780.53755699543, 8890.93308802935, 9156.771080723036, 9204.079592040876, 9204.355695101412, 9327.0, 9088.091190880992, 9183.908160918305, 9183.90816091844, 9187.862182067274, 9194.402363846462, 6930.7227710891, 7050.506464547432, 7052.047973523611, 7129.857402851914, 7136.464765142531, 6876.621785801629, 6914.827129321807, 6937.375636192565, 6945.548539344926, 6946.374826265458, 7721.112072111554, 7989.0, 7991.92008079935, 8100.473469224482, 8121.309688676529, 8276.875846862182, 8340.833183336177, 8512.0, 8533.445326053787, 8540.74377768668, 6233.252009758858, 6257.217847769029, 6291.339409361951, 6311.463525600192, 6311.779087731908, 7269.200387957426, 7388.187299396955, 7417.443691723034, 7442.292982166686, 7442.96278518604, 6916.861662766817, 7029.683664235019, 7051.765940960209, 7125.572465652078, 7170.713171473083, 5718.742656580381, 6785.185777706707, 6872.312768723088, 6896.0, 7025.718971241093, 6700.128983232149, 6948.6178260196175, 6955.374016338554, 6980.127484064492, 6980.127484064492, 6399.55203135776, 6431.035344698333, 6436.9034464484, 6442.387973142544, 6444.097826304415, 6433.035044743326, 6465.935340646533, 6502.837429064122, 6511.413972742476, 6523.510736694672], "unit": "ms", "name": "mootools.html", "value": 4273.765628111048}, {"lowerIsBetter": false, "replicates": [346.979181249125, 348.7938787171142, 350.86491700695257, 352.55754028693985, 354.6683850599688, 5901.645901245923, 6022.397760223976, 6033.276006879175, 6061.66660833654, 6094.573379863411, 5729.0833466645345, 5739.626924249931, 5885.470307672309, 5949.732262048207, 5960.672163031031, 4558.452985641711, 4581.633469322446, 4597.770111494428, 4612.215923293048, 4619.2609182530805, 4581.083783243348, 4599.126166028444, 4608.308753686957, 4611.469680986679, 4620.5148459411785, 4375.4311939447825, 4441.555844415565, 4471.575200355977, 4518.118966801475, 4523.389342438778, 4551.0, 4552.339910712944, 4554.658400619966, 4555.542226487525, 4571.954280457203, 4007.31875581149, 4007.83968641254, 4014.9197016059807, 4018.136100738326, 4020.2562525932794, 5679.602427830054, 5685.289338832646, 5685.658860468344, 5687.146927960797, 5690.743916523725, 5143.819966301162, 5268.420473747885, 5283.9471605284225, 5289.894202115975, 5293.0, 6342.5243106767175, 6350.237971443456, 6354.904676429857, 6358.809235722936, 6360.809175724736, 4364.886953826785, 4372.5627437256335, 4375.584319489643, 4376.715513491613, 4379.956200437987, 6478.74085036598, 6537.93462065378, 6601.075849381091, 6619.0, 6640.767573134917, 4514.480834704051, 4527.3435351873795, 4527.615152712056, 4528.230200865861, 4535.024969631545, 4587.311903214544, 4588.220002599566, 4594.19601569724, 4594.19601569724, 4594.356790049395, 5292.788288468495, 5295.629305948623, 5300.3639563252655, 5300.390455097636, 5311.362636483646, 6274.372562743689, 6397.10440538325, 6404.391582799627, 6413.230412350455, 6414.1340918976275, 1611.935522579107, 1625.7398816189352, 1626.4795265514922, 1627.031916009972, 1627.8116974608365, 1071.132382769959, 1076.3918386111977, 1079.681493959284, 1093.103655002906, 1101.5263436722287, 6135.9079613805825, 6146.846328841814, 6147.938520614826, 6152.538559608047, 6154.107654390088, 6591.242007169141, 6601.075849380994, 6604.900926486107, 6609.405153536109, 6615.140031795932, 5973.402659734079, 6041.486473649789, 6072.939270607326, 6125.479334256638, 6217.098520714471, 3549.982250088733, 3577.8032208228797, 3584.5698516178227, 3586.5158203642695, 3589.2462582857893, 6867.896981545281, 6873.759418420332, 6875.243723190445, 6876.346747059021, 6879.105716256855, 6651.534392592472, 6826.931730682729, 6827.829304267334, 6831.28271531492, 6832.145981752281, 6854.34883686049, 6861.279565645636, 6863.862722745517, 6864.897026544606, 6882.8967565486555, 6518.445932095825, 6632.767853125118, 6641.867162656817, 6648.102506161799, 6648.401643851883, 6566.9343306566325, 6580.177477815273, 6584.604923704593, 6586.736530538725, 6589.044588534732, 6858.6227757471825, 6860.176778786577, 6861.656917154022, 6866.073080134118, 6866.34769696878, 4538.841140560065, 4539.273716205391, 4540.614047805973, 4543.0, 4545.772711364485, 6491.545591808717, 6597.406233439014, 6621.443960669335, 6631.33686631333, 6632.668366581555, 6384.138141350858, 6436.806895793134, 6443.838904027437, 6444.871102578016, 6449.903516402129, 6327.114204011534, 6430.164078669743, 6435.517336199898, 6439.903401449075, 6444.0, 6252.406021427957, 6282.120503129657, 6293.968530157503, 6314.116023756769, 6315.957866951901, 6431.710573024132, 6438.5171112165835, 6444.516661250519, 6445.967770161119, 6450.709718062604, 6184.6598437086395, 6289.49684025286, 6298.149749783721, 6299.0, 6304.779332723333], "unit": "ms", "name": "prototype.html", "value": 4818.91315162467}, {"lowerIsBetter": false, "replicates": [733.2191216354582, 775.5308038636629, 793.4247670438932, 793.8610743119953, 888.4536010353632, 13256.536021239246, 13413.262270575115, 13475.528356507511, 13483.595492135228, 13492.460301587924, 12372.257664540133, 12524.812127818068, 12603.495860165582, 12612.810807837866, 13540.729185416285, 809.9109097999216, 832.4464231286188, 834.4742812028413, 834.837206744685, 835.0230230630177, 819.319964429525, 842.7008412013708, 845.3364109174299, 846.3652260804397, 846.4582667093065, 868.7524055644143, 884.6196135661663, 884.8716936044269, 885.4333226734863, 886.1271647427311, 874.5583480342418, 879.2131042716765, 881.7619242804407, 882.5851849630695, 882.6910581296559, 635.9268684101295, 643.7392855893371, 645.7578408096964, 647.2459584583954, 647.488484097567, 12600.432980515807, 12789.232646041268, 12799.360031998363, 12800.103992720513, 12808.10343275971, 10757.78484430307, 11003.889961100447, 11142.721431964266, 11349.09207263417, 11350.829737553944, 9867.40795552269, 9944.105030547285, 9984.800303993952, 10099.89900100997, 10143.34068285559, 3713.8885833425043, 3718.8326525306425, 3757.7933213673236, 3797.525309336333, 3864.3623802072625, 41623.37564936528, 44083.338749918774, 48006.27990580144, 50140.749296253285, 50288.0, 9845.704628861145, 10030.253434508666, 10081.49592520371, 10132.24008199388, 10226.23303252259, 17914.731279030824, 17968.640627187382, 17973.550661233574, 17979.910100449677, 18004.63990720178, 7231.493795434374, 7514.887276690854, 7614.086309642767, 7677.30904218623, 7773.689052437952, 4171.687123465752, 4172.645325147336, 4185.727927684691, 4194.370844373308, 4219.810108545123, 3258.885938992124, 3295.8187299698266, 3297.9175520612175, 3298.868045278162, 3299.538064670948, 590.5659340384814, 601.1133577972543, 605.4157737783061, 620.314552419579, 629.8456878064903, 664.7606861529846, 671.8891382921861, 680.9693563789641, 691.5781373362245, 718.9352958233679, 7952.1205323911045, 8315.667373305, 8369.874451883226, 8396.244338009486, 8448.07071222165, 674.3896773420063, 682.7234969837224, 703.4021082080191, 707.4163814852665, 717.1716667249335, 4105.281575724236, 4113.670906327487, 4234.301340278819, 4256.3189889617515, 4298.41971333866, 58044.4195558039, 58146.60387357388, 58686.4131358681, 58783.0, 58969.705151474824, 44454.333185001604, 45914.0, 50561.74719126454, 51775.48224517782, 51823.74088129611, 37819.62180378216, 46608.30087548689, 48925.5107448921, 49143.754281228365, 49671.50328496741, 8610.354223433393, 8625.69810056645, 8796.47221166732, 8990.415622984485, 9427.104425079606, 32951.0, 33297.0, 34955.47566786551, 35271.823640882656, 35649.0, 43137.56862431461, 44995.32507012463, 45043.099138017715, 48131.278030828864, 48597.27104093371, 10792.838107428552, 10805.621803236849, 11303.321800691985, 11531.423428828693, 11650.359230242419, 12549.74900502003, 12616.87383126157, 12656.810147847606, 12679.556215532411, 12776.936115319735, 11276.0, 11449.42752862337, 11486.425678715863, 11570.479328430409, 11956.282623042645, 11970.58102966392, 12212.32832194238, 12257.816132757838, 12371.257724536557, 12739.044571656977, 11798.705032374259, 12047.096467765128, 12168.878311216775, 12257.570985015482, 12362.258264504158, 9198.2181514572, 9472.810543788948, 9887.456189909624, 10359.896401035892, 10512.789744205225, 8982.86525702128, 8992.235659968976, 9381.0, 9971.152452041368, 10045.849312260469], "unit": "ms", "name": "yui.html", "value": 6388.035962211806}], "name": "dromaeo_css", "value": 6965.119607866893}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 105.0, 110.0, 111.0, 111.0, 97.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.5}, {"lowerIsBetter": true, "replicates": [150.0, 124.0, 152.0, 160.0, 125.0, 152.0, 151.0, 123.0, 170.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.5}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 159.0, 162.0, 160.0, 159.0, 165.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 82.0, 95.0, 71.0, 73.0, 72.0, 106.0, 92.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.6}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 134.0, 90.0, 90.0, 90.0, 136.0, 90.0, 91.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 103.4}, {"lowerIsBetter": true, "replicates": [114.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [58.0, 56.0, 59.0, 56.0, 57.0, 57.0, 56.0, 66.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 67.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 67.9}, {"lowerIsBetter": true, "replicates": [82.0, 84.0, 86.0, 85.0, 93.0, 85.0, 86.0, 84.0, 88.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.9}, {"lowerIsBetter": true, "replicates": [116.0, 120.0, 113.0, 114.0, 120.0, 117.0, 123.0, 124.0, 123.0, 122.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.2}, {"lowerIsBetter": true, "replicates": [142.0, 150.0, 151.0, 146.0, 152.0, 141.0, 152.0, 151.0, 150.0, 147.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.2}, {"lowerIsBetter": true, "replicates": [69.0, 66.0, 68.0, 67.0, 67.0, 69.0, 66.0, 67.0, 68.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.3}], "name": "kraken", "value": 1493.5000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.94606485253382], "unit": "score", "name": "Richards", "value": 216.64837400000005}, {"lowerIsBetter": false, "replicates": [188.28845791752966], "unit": "score", "name": "DeltaBlue", "value": 351.152698}, {"lowerIsBetter": false, "replicates": [280.97780275358247], "unit": "score", "name": "Encrypt", "value": 947.3381789999996}, {"lowerIsBetter": false, "replicates": [5025.125628140703], "unit": "score", "name": "Decrypt", "value": 52.970019}, {"lowerIsBetter": false, "replicates": [1962.7450980392157], "unit": "score", "name": "RayTrace", "value": 377.0173726273725}, {"lowerIsBetter": false, "replicates": [775.1937984496124], "unit": "score", "name": "Earley", "value": 859.7372699999999}, {"lowerIsBetter": false, "replicates": [12345.67901234568], "unit": "score", "name": "Boyer", "value": 53.98350299999995}, {"lowerIsBetter": false, "replicates": [26473.684210526317], "unit": "score", "name": "RegExp", "value": 34.410964214711704}, {"lowerIsBetter": false, "replicates": [593.1198102016607], "unit": "score", "name": "Splay", "value": 137.39382600000002}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19969.138120367363}]} 19:19:36 INFO - RETURN: dromaeo_css: 6965.12 19:19:36 INFO - RETURN: kraken: 1493.50 19:19:36 INFO - RETURN: v8_7: 19969.14 19:19:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "19969.14"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1493.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6965.12"}}} 19:19:36 INFO - Return code: 0 19:19:36 INFO - # TBPL SUCCESS # 19:19:36 INFO - Running post-action listener: _resource_record_post_action 19:19:36 INFO - Running post-run listener: _resource_record_post_run 19:19:37 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 12288; Write bytes: 513667072; Read time: 208; Write time: 466748 19:19:37 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 181637120; Read time: 96; Write time: 273608 19:19:37 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 277504000; Read time: 96; Write time: 170244 19:19:37 INFO - Running post-run listener: _upload_blobber_files 19:19:37 INFO - Blob upload gear active. 19:19:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:19:37 INFO - Copying logs to upload dir... 19:19:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.787697 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-22 19:19:37.777696) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-22 19:19:37.781709) ========= 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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010088 build_url: 'https://queue.taskcluster.net/v1/task/zAKa3uBZRSeNavryusUa9A/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-22 19:19:37.833260) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 19:19:37.833608) ========= 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=d6a7e047ef025f707024e163000001c3-1448236923.442875-404014853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004636 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-22 19:19:37.883021) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-22 19:19:37.883350) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-22 19:19:37.883667) ========= ========= Total master_lag: 0.26 =========