builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1448301039.6 results: success (0) buildid: 20151123073219 builduid: c24c09f58e0d4bb7b4d21eec7a117cd6 revision: d3d286102ba7f8801e9dfe12d534f49554ba50c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.603320) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.603855) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.604172) ========= 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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006733 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.625765) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.626136) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.639311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.639722) ========= 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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004604 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.689080) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.689384) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.689753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.690047) ========= 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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 09:50:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 55.3M=0s 2015-11-23 09:50:40 (55.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.185982 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.888524) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.888912) ========= 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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015009 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:39.938955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 09:50:39.939370) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d3d286102ba7f8801e9dfe12d534f49554ba50c0 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d3d286102ba7f8801e9dfe12d534f49554ba50c0 --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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 09:50:40,219 truncating revision to first 12 chars 2015-11-23 09:50:40,219 Setting DEBUG logging. 2015-11-23 09:50:40,219 attempt 1/10 2015-11-23 09:50:40,219 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d3d286102ba7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 09:50:40,468 unpacking tar archive at: mozilla-central-d3d286102ba7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.537388 ========= master_lag: 0.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 09:50:41.392938) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:41.393268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:41.414866) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:41.415162) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 09:50:41.415508) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-11-23 09:50:41.415780) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:50:41 INFO - MultiFileLogger online at 20151123 09:50:41 in /builds/slave/test-pgo 09:50:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:50:41 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:50:41 INFO - {'append_to_log': False, 09:50:41 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:50:41 INFO - 'blob_upload_branch': 'Firefox', 09:50:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:50:41 INFO - 'branch': 'Firefox', 09:50:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:50:41 INFO - 'config_files': ('talos/linux_config.py',), 09:50:41 INFO - 'default_actions': ('clobber', 09:50:41 INFO - 'read-buildbot-config', 09:50:41 INFO - 'download-and-extract', 09:50:41 INFO - 'populate-webroot', 09:50:41 INFO - 'create-virtualenv', 09:50:41 INFO - 'install', 09:50:41 INFO - 'run-tests'), 09:50:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:50:41 INFO - 'download_minidump_stackwalk': True, 09:50:41 INFO - 'download_symbols': 'ondemand', 09:50:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:50:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:50:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:50:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:50:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:50:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:50:41 INFO - 'installer_path': 'installer.exe', 09:50:41 INFO - 'log_level': 'info', 09:50:41 INFO - 'log_name': 'talos', 09:50:41 INFO - 'log_to_console': True, 09:50:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:50:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:50:41 INFO - 'opt_config_files': (), 09:50:41 INFO - 'pip_index': False, 09:50:41 INFO - 'sps_profile': False, 09:50:41 INFO - 'sps_profile_interval': 0, 09:50:41 INFO - 'suite': 'dromaeojs', 09:50:41 INFO - 'system_bits': '64', 09:50:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:50:41 INFO - 'title': 'talos-linux64-ix-031', 09:50:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:50:41 INFO - 'use_talos_json': True, 09:50:41 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:50:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:50:41 INFO - 'work_dir': 'build'} 09:50:41 INFO - ##### 09:50:41 INFO - ##### Running clobber step. 09:50:41 INFO - ##### 09:50:41 INFO - Running pre-action listener: _resource_record_pre_action 09:50:41 INFO - Running main action method: clobber 09:50:41 INFO - rmtree: /builds/slave/test-pgo/build 09:50:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:50:42 INFO - Running post-action listener: _resource_record_post_action 09:50:42 INFO - ##### 09:50:42 INFO - ##### Running read-buildbot-config step. 09:50:42 INFO - ##### 09:50:42 INFO - Running pre-action listener: _resource_record_pre_action 09:50:42 INFO - Running main action method: read_buildbot_config 09:50:42 INFO - Using buildbot properties: 09:50:42 INFO - { 09:50:42 INFO - "properties": { 09:50:42 INFO - "buildnumber": 576, 09:50:42 INFO - "product": "firefox", 09:50:42 INFO - "script_repo_revision": "production", 09:50:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:50:42 INFO - "repository": "", 09:50:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:50:42 INFO - "buildid": "20151123073219", 09:50:42 INFO - "slavename": "talos-linux64-ix-031", 09:50:42 INFO - "pgo_build": "True", 09:50:42 INFO - "basedir": "/builds/slave/test-pgo", 09:50:42 INFO - "project": "", 09:50:42 INFO - "platform": "ubuntu64_hw", 09:50:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:50:42 INFO - "slavebuilddir": "test", 09:50:42 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:50:42 INFO - "branch": "mozilla-central", 09:50:42 INFO - "repo_path": "mozilla-central", 09:50:42 INFO - "stage_platform": "linux64-pgo", 09:50:42 INFO - "builduid": "c24c09f58e0d4bb7b4d21eec7a117cd6", 09:50:42 INFO - "revision": "d3d286102ba7f8801e9dfe12d534f49554ba50c0" 09:50:42 INFO - }, 09:50:42 INFO - "sourcestamp": { 09:50:42 INFO - "repository": "", 09:50:42 INFO - "hasPatch": false, 09:50:42 INFO - "project": "", 09:50:42 INFO - "branch": "mozilla-central-linux64-pgo-talos", 09:50:42 INFO - "changes": [ 09:50:42 INFO - { 09:50:42 INFO - "category": null, 09:50:42 INFO - "files": [ 09:50:42 INFO - { 09:50:42 INFO - "url": null, 09:50:42 INFO - "name": "https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 09:50:42 INFO - } 09:50:42 INFO - ], 09:50:42 INFO - "repository": "", 09:50:42 INFO - "rev": "d3d286102ba7f8801e9dfe12d534f49554ba50c0", 09:50:42 INFO - "who": "sendchange", 09:50:42 INFO - "when": 1448301031, 09:50:42 INFO - "number": 6695969, 09:50:42 INFO - "comments": "", 09:50:42 INFO - "project": "", 09:50:42 INFO - "at": "Mon 23 Nov 2015 09:50:31", 09:50:42 INFO - "branch": "mozilla-central-linux64-pgo-talos", 09:50:42 INFO - "revlink": "", 09:50:42 INFO - "properties": [ 09:50:42 INFO - [ 09:50:42 INFO - "buildid", 09:50:42 INFO - "20151123073219", 09:50:42 INFO - "Change" 09:50:42 INFO - ], 09:50:42 INFO - [ 09:50:42 INFO - "builduid", 09:50:42 INFO - "c24c09f58e0d4bb7b4d21eec7a117cd6", 09:50:42 INFO - "Change" 09:50:42 INFO - ], 09:50:42 INFO - [ 09:50:42 INFO - "pgo_build", 09:50:42 INFO - "True", 09:50:42 INFO - "Change" 09:50:42 INFO - ] 09:50:42 INFO - ], 09:50:42 INFO - "revision": "d3d286102ba7f8801e9dfe12d534f49554ba50c0" 09:50:42 INFO - } 09:50:42 INFO - ], 09:50:42 INFO - "revision": "d3d286102ba7f8801e9dfe12d534f49554ba50c0" 09:50:42 INFO - } 09:50:42 INFO - } 09:50:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 09:50:42 INFO - Running post-action listener: _resource_record_post_action 09:50:42 INFO - ##### 09:50:42 INFO - ##### Running download-and-extract step. 09:50:42 INFO - ##### 09:50:42 INFO - Running pre-action listener: _resource_record_pre_action 09:50:42 INFO - Running main action method: download_and_extract 09:50:42 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:42 INFO - https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:50:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/test_packages.json 09:50:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/test_packages.json 09:50:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:50:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:50:47 INFO - Downloaded 1302 bytes. 09:50:47 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:50:47 INFO - Using the following test package requirements: 09:50:47 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:50:47 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:50:47 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'jsshell-linux-x86_64.zip'], 09:50:47 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:50:47 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:50:47 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:50:47 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:50:47 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:50:47 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 09:50:47 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:50:47 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:47 INFO - https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:50:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:50:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:50:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:50:57 INFO - Downloaded 22527334 bytes. 09:50:57 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 09:50:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:50:57 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 09:50:58 INFO - Return code: 0 09:50:58 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 09:50: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')]} 09:50:58 INFO - https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:50:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:50:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:50:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:50:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:50:59 INFO - Downloaded 22527334 bytes. 09:50:59 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 09:50:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 09:50:59 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 09:51:00 INFO - Return code: 0 09:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:00 INFO - https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:51:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:51:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:51:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:51:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:51:02 INFO - Downloaded 11130997 bytes. 09:51:02 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 09:51:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 09:51:02 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 09:51:02 INFO - Return code: 0 09:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:02 INFO - https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:51:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:51:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:51:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:51:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/qsWPhsHnRuazZXhAs6utwQ/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 09:51:03 INFO - Downloaded 57551574 bytes. 09:51:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:51:03 INFO - mkdir: /builds/slave/test-pgo/properties 09:51:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:51:03 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:51:03 INFO - Contents: 09:51:03 INFO - build_url:https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 09:51:03 INFO - Running post-action listener: _resource_record_post_action 09:51:03 INFO - Running post-action listener: set_extra_try_arguments 09:51:03 INFO - ##### 09:51:03 INFO - ##### Running populate-webroot step. 09:51:03 INFO - ##### 09:51:03 INFO - Running pre-action listener: _resource_record_pre_action 09:51:03 INFO - Running main action method: populate_webroot 09:51:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:51:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:51:03 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:51:03 INFO - '../fennec_ids.txt'], 09:51:03 INFO - 'tests': ['tcheck2']}, 09:51:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:51:03 INFO - '--tppagecycles', 09:51:03 INFO - '7'], 09:51:03 INFO - 'tests': ['tsvgm']}}, 09:51:03 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:51:03 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:51:03 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:51:03 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:51:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'tests': ['damp', 'tps']}, 09:51:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['damp', 'tps']}, 09:51:03 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:51:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:51:03 INFO - 'other': {'tests': ['a11yr', 09:51:03 INFO - 'ts_paint', 09:51:03 INFO - 'tpaint', 09:51:03 INFO - 'sessionrestore', 09:51:03 INFO - 'sessionrestore_no_auto_restore']}, 09:51:03 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['a11yr', 09:51:03 INFO - 'ts_paint', 09:51:03 INFO - 'tpaint', 09:51:03 INFO - 'sessionrestore', 09:51:03 INFO - 'sessionrestore_no_auto_restore']}, 09:51:03 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['a11yr', 09:51:03 INFO - 'ts_paint', 09:51:03 INFO - 'tpaint', 09:51:03 INFO - 'sessionrestore', 09:51:03 INFO - 'sessionrestore_no_auto_restore']}, 09:51:03 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['a11yr', 09:51:03 INFO - 'ts_paint', 09:51:03 INFO - 'tpaint', 09:51:03 INFO - 'sessionrestore', 09:51:03 INFO - 'sessionrestore_no_auto_restore']}, 09:51:03 INFO - 'other_l64': {'tests': ['a11yr', 09:51:03 INFO - 'ts_paint', 09:51:03 INFO - 'tpaint', 09:51:03 INFO - 'sessionrestore', 09:51:03 INFO - 'sessionrestore_no_auto_restore']}, 09:51:03 INFO - 'other_nol64': {'tests': ['a11yr', 09:51:03 INFO - 'ts_paint', 09:51:03 INFO - 'tpaint', 09:51:03 INFO - 'sessionrestore', 09:51:03 INFO - 'sessionrestore_no_auto_restore']}, 09:51:03 INFO - 'svgr': {'tests': ['tsvgx', 09:51:03 INFO - 'tsvgr_opacity', 09:51:03 INFO - 'tart', 09:51:03 INFO - 'tscrollx', 09:51:03 INFO - 'cart']}, 09:51:03 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['tsvgx', 09:51:03 INFO - 'tsvgr_opacity', 09:51:03 INFO - 'tart', 09:51:03 INFO - 'tscrollx', 09:51:03 INFO - 'cart']}, 09:51:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'tests': ['tp5o']}, 09:51:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'talos_options': ['--e10s'], 09:51:03 INFO - 'tests': ['tp5o']}, 09:51:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'talos_options': ['--xperf_path', 09:51:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:03 INFO - 'tests': ['tp5n']}, 09:51:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:03 INFO - 'talos_options': ['--e10s', 09:51:03 INFO - '--xperf_path', 09:51:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:03 INFO - 'tests': ['tp5n']}}, 09:51:03 INFO - 'talos.zip': {'path': '', 09:51:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:51:03 INFO - Running post-action listener: _resource_record_post_action 09:51:03 INFO - ##### 09:51:03 INFO - ##### Running create-virtualenv step. 09:51:03 INFO - ##### 09:51:03 INFO - Running pre-action listener: _resource_record_pre_action 09:51:03 INFO - Running main action method: create_virtualenv 09:51:03 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:51:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:51:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:51:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:51:03 INFO - Using real prefix '/usr' 09:51:03 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:51:04 INFO - Installing distribute.............................................................................................................................................................................................done. 09:51:06 INFO - Installing pip.................done. 09:51:06 INFO - Return code: 0 09:51:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:51:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:51:06 INFO - Using env: {'DISPLAY': ':0', 09:51:06 INFO - 'HOME': '/home/cltbld', 09:51:06 INFO - 'LANG': 'en_US.UTF-8', 09:51:06 INFO - 'LANGUAGE': 'en_US:en', 09:51:06 INFO - 'LOGNAME': 'cltbld', 09:51:06 INFO - 'MAIL': '/var/mail/cltbld', 09:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:06 INFO - 'MOZ_NO_REMOTE': '1', 09:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:06 INFO - 'NO_EM_RESTART': '1', 09:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:06 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:06 INFO - 'SHELL': '/bin/bash', 09:51:06 INFO - 'SHLVL': '1', 09:51:06 INFO - 'TERM': 'linux', 09:51:06 INFO - 'TMOUT': '86400', 09:51:06 INFO - 'USER': 'cltbld', 09:51:06 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:06 INFO - '_': '/tools/buildbot/bin/python'} 09:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:51:06 INFO - Cleaning up... 09:51:06 INFO - Return code: 0 09:51:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:51:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:51:06 INFO - Using env: {'DISPLAY': ':0', 09:51:06 INFO - 'HOME': '/home/cltbld', 09:51:06 INFO - 'LANG': 'en_US.UTF-8', 09:51:06 INFO - 'LANGUAGE': 'en_US:en', 09:51:06 INFO - 'LOGNAME': 'cltbld', 09:51:06 INFO - 'MAIL': '/var/mail/cltbld', 09:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:06 INFO - 'MOZ_NO_REMOTE': '1', 09:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:06 INFO - 'NO_EM_RESTART': '1', 09:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:06 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:06 INFO - 'SHELL': '/bin/bash', 09:51:06 INFO - 'SHLVL': '1', 09:51:06 INFO - 'TERM': 'linux', 09:51:06 INFO - 'TMOUT': '86400', 09:51:06 INFO - 'USER': 'cltbld', 09:51:06 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:06 INFO - '_': '/tools/buildbot/bin/python'} 09:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:06 INFO - Downloading/unpacking psutil>=0.7.1 09:51: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 09:51: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 09:51: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 09:51: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 09:51: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 09:51: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 09:51:08 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 09:51:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:51:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:51:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:08 INFO - Installing collected packages: psutil 09:51:08 INFO - Running setup.py install for psutil 09:51:08 INFO - building 'psutil._psutil_linux' extension 09:51:08 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 09:51:08 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 09:51:08 INFO - building 'psutil._psutil_posix' extension 09:51:08 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 09:51:08 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 09:51:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:09 INFO - Successfully installed psutil 09:51:09 INFO - Cleaning up... 09:51:09 INFO - Return code: 0 09:51:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:51:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:51:09 INFO - Using env: {'DISPLAY': ':0', 09:51:09 INFO - 'HOME': '/home/cltbld', 09:51:09 INFO - 'LANG': 'en_US.UTF-8', 09:51:09 INFO - 'LANGUAGE': 'en_US:en', 09:51:09 INFO - 'LOGNAME': 'cltbld', 09:51:09 INFO - 'MAIL': '/var/mail/cltbld', 09:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:09 INFO - 'MOZ_NO_REMOTE': '1', 09:51:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:09 INFO - 'NO_EM_RESTART': '1', 09:51:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:09 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:09 INFO - 'SHELL': '/bin/bash', 09:51:09 INFO - 'SHLVL': '1', 09:51:09 INFO - 'TERM': 'linux', 09:51:09 INFO - 'TMOUT': '86400', 09:51:09 INFO - 'USER': 'cltbld', 09:51:09 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:09 INFO - '_': '/tools/buildbot/bin/python'} 09:51:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:51:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:51:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:51:11 INFO - Installing collected packages: mozsystemmonitor 09:51:11 INFO - Running setup.py install for mozsystemmonitor 09:51:11 INFO - Successfully installed mozsystemmonitor 09:51:11 INFO - Cleaning up... 09:51:11 INFO - Return code: 0 09:51:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:11 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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:11 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 09:51:11 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 09:51:11 INFO - Using env: {'DISPLAY': ':0', 09:51:11 INFO - 'HOME': '/home/cltbld', 09:51:11 INFO - 'LANG': 'en_US.UTF-8', 09:51:11 INFO - 'LANGUAGE': 'en_US:en', 09:51:11 INFO - 'LOGNAME': 'cltbld', 09:51:11 INFO - 'MAIL': '/var/mail/cltbld', 09:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:11 INFO - 'MOZ_NO_REMOTE': '1', 09:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:11 INFO - 'NO_EM_RESTART': '1', 09:51:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:11 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:11 INFO - 'SHELL': '/bin/bash', 09:51:11 INFO - 'SHLVL': '1', 09:51:11 INFO - 'TERM': 'linux', 09:51:11 INFO - 'TMOUT': '86400', 09:51:11 INFO - 'USER': 'cltbld', 09:51:11 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:11 INFO - '_': '/tools/buildbot/bin/python'} 09:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:11 INFO - Downloading/unpacking blobuploader==1.2.4 09:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:13 INFO - Downloading blobuploader-1.2.4.tar.gz 09:51:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:51:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:51:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:51:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:51:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:13 INFO - Downloading docopt-0.6.1.tar.gz 09:51:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:51:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:51:13 INFO - Installing collected packages: blobuploader, requests, docopt 09:51:13 INFO - Running setup.py install for blobuploader 09:51:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:51:13 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:51:13 INFO - Running setup.py install for requests 09:51:14 INFO - Running setup.py install for docopt 09:51:14 INFO - Successfully installed blobuploader requests docopt 09:51:14 INFO - Cleaning up... 09:51:14 INFO - Return code: 0 09:51:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:14 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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:14 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 09:51:14 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 09:51:14 INFO - Using env: {'DISPLAY': ':0', 09:51:14 INFO - 'HOME': '/home/cltbld', 09:51:14 INFO - 'LANG': 'en_US.UTF-8', 09:51:14 INFO - 'LANGUAGE': 'en_US:en', 09:51:14 INFO - 'LOGNAME': 'cltbld', 09:51:14 INFO - 'MAIL': '/var/mail/cltbld', 09:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:14 INFO - 'MOZ_NO_REMOTE': '1', 09:51:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:14 INFO - 'NO_EM_RESTART': '1', 09:51:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:14 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:14 INFO - 'SHELL': '/bin/bash', 09:51:14 INFO - 'SHLVL': '1', 09:51:14 INFO - 'TERM': 'linux', 09:51:14 INFO - 'TMOUT': '86400', 09:51:14 INFO - 'USER': 'cltbld', 09:51:14 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:14 INFO - '_': '/tools/buildbot/bin/python'} 09:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:51:14 INFO - Running setup.py (path:/tmp/pip-Yh41yz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:51:14 INFO - Running setup.py (path:/tmp/pip-1hdXCz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:51:14 INFO - Running setup.py (path:/tmp/pip-YeCtEz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:51:14 INFO - Running setup.py (path:/tmp/pip-2hug4B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:51:14 INFO - Running setup.py (path:/tmp/pip-5QJnUr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:51:14 INFO - Running setup.py (path:/tmp/pip-yqq4sa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:51:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:51:14 INFO - Running setup.py (path:/tmp/pip-pIi7C7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:51:15 INFO - Running setup.py (path:/tmp/pip-uzzPPm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:51:15 INFO - Running setup.py (path:/tmp/pip-WzgQiW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:51:15 INFO - Running setup.py (path:/tmp/pip-JKiO75-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:51:15 INFO - Running setup.py (path:/tmp/pip-RRwDeN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:51:15 INFO - Running setup.py (path:/tmp/pip-8NsG0g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:51:15 INFO - Running setup.py (path:/tmp/pip-Pf7K3j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:51:15 INFO - Running setup.py (path:/tmp/pip-tUXK1b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:51:15 INFO - Running setup.py (path:/tmp/pip-mfKZAz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:51:15 INFO - Running setup.py (path:/tmp/pip-HE4yI1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:51:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:51:15 INFO - Running setup.py (path:/tmp/pip-HvbUrJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:51:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:51:15 INFO - Running setup.py install for manifestparser 09:51:15 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:51:15 INFO - Running setup.py install for mozcrash 09:51:15 INFO - Running setup.py install for mozdebug 09:51:16 INFO - Running setup.py install for mozdevice 09:51:16 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Running setup.py install for mozfile 09:51:16 INFO - Running setup.py install for mozhttpd 09:51:16 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Running setup.py install for mozinfo 09:51:16 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Running setup.py install for mozInstall 09:51:16 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Running setup.py install for mozleak 09:51:16 INFO - Running setup.py install for mozlog 09:51:16 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Running setup.py install for moznetwork 09:51:16 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:51:16 INFO - Running setup.py install for mozprocess 09:51:17 INFO - Running setup.py install for mozprofile 09:51:17 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:51:17 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:51:17 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:51:17 INFO - Running setup.py install for mozrunner 09:51:17 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:51:17 INFO - Running setup.py install for mozscreenshot 09:51:17 INFO - Running setup.py install for moztest 09:51:17 INFO - Running setup.py install for mozversion 09:51:17 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:51:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:51:17 INFO - Cleaning up... 09:51:17 INFO - Return code: 0 09:51:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:17 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 09:51:17 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 09:51:17 INFO - Using env: {'DISPLAY': ':0', 09:51:17 INFO - 'HOME': '/home/cltbld', 09:51:17 INFO - 'LANG': 'en_US.UTF-8', 09:51:17 INFO - 'LANGUAGE': 'en_US:en', 09:51:17 INFO - 'LOGNAME': 'cltbld', 09:51:17 INFO - 'MAIL': '/var/mail/cltbld', 09:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:17 INFO - 'MOZ_NO_REMOTE': '1', 09:51:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:17 INFO - 'NO_EM_RESTART': '1', 09:51:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:17 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:17 INFO - 'SHELL': '/bin/bash', 09:51:17 INFO - 'SHLVL': '1', 09:51:17 INFO - 'TERM': 'linux', 09:51:17 INFO - 'TMOUT': '86400', 09:51:17 INFO - 'USER': 'cltbld', 09:51:17 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:17 INFO - '_': '/tools/buildbot/bin/python'} 09:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:51:17 INFO - Running setup.py (path:/tmp/pip-TReDPV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:51:17 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)) 09:51:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:51:17 INFO - Running setup.py (path:/tmp/pip-CDTgyl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:51:17 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)) 09:51:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:51:17 INFO - Running setup.py (path:/tmp/pip-fHyRMf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:51:17 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)) 09:51:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:51:18 INFO - Running setup.py (path:/tmp/pip-CxGnTs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:51:18 INFO - Running setup.py (path:/tmp/pip-JnBvFi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:51:18 INFO - Running setup.py (path:/tmp/pip-XdQ5Ck-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:51:18 INFO - Running setup.py (path:/tmp/pip-aSy1O3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:51:18 INFO - Running setup.py (path:/tmp/pip-ICsg0o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:51:18 INFO - Running setup.py (path:/tmp/pip-FmVbvB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:51:18 INFO - Running setup.py (path:/tmp/pip-hObpqX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:51:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:51:18 INFO - Running setup.py (path:/tmp/pip-8tsZ4k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:51:18 INFO - Running setup.py (path:/tmp/pip-cqwm53-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:51:18 INFO - Running setup.py (path:/tmp/pip-ksT3KA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:51:18 INFO - Running setup.py (path:/tmp/pip-gPLzKm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:51:18 INFO - Running setup.py (path:/tmp/pip-i6be7p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:51:18 INFO - Running setup.py (path:/tmp/pip-xxlYsu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:51:18 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)) 09:51:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:51:18 INFO - Running setup.py (path:/tmp/pip-kovqF1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:51:19 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)) 09:51:19 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)) 09:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:19 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)) 09:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:51:19 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)) 09:51:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:20 INFO - Downloading blessings-1.5.1.tar.gz 09:51:20 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 09:51:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:51:20 INFO - Installing collected packages: blessings 09:51:20 INFO - Running setup.py install for blessings 09:51:20 INFO - Successfully installed blessings 09:51:20 INFO - Cleaning up... 09:51:20 INFO - Return code: 0 09:51:20 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:51:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:51:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:51:21 INFO - Reading from file tmpfile_stdout 09:51:21 INFO - Current package versions: 09:51:21 INFO - argparse == 1.2.1 09:51:21 INFO - blessings == 1.5.1 09:51:21 INFO - blobuploader == 1.2.4 09:51:21 INFO - docopt == 0.6.1 09:51:21 INFO - manifestparser == 1.1 09:51:21 INFO - mozInstall == 1.12 09:51:21 INFO - mozcrash == 0.16 09:51:21 INFO - mozdebug == 0.1 09:51:21 INFO - mozdevice == 0.47 09:51:21 INFO - mozfile == 1.2 09:51:21 INFO - mozhttpd == 0.7 09:51:21 INFO - mozinfo == 0.9 09:51:21 INFO - mozleak == 0.1 09:51:21 INFO - mozlog == 3.1 09:51:21 INFO - moznetwork == 0.27 09:51:21 INFO - mozprocess == 0.22 09:51:21 INFO - mozprofile == 0.27 09:51:21 INFO - mozrunner == 6.11 09:51:21 INFO - mozscreenshot == 0.1 09:51:21 INFO - mozsystemmonitor == 0.0 09:51:21 INFO - moztest == 0.7 09:51:21 INFO - mozversion == 1.4 09:51:21 INFO - psutil == 3.1.1 09:51:21 INFO - requests == 1.2.3 09:51:21 INFO - wsgiref == 0.1.2 09:51:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:21 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 0x7fb2a0f91c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc3a6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcec7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, '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': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:51:21 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 09:51:21 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 09:51:21 INFO - Using env: {'DISPLAY': ':0', 09:51:21 INFO - 'HOME': '/home/cltbld', 09:51:21 INFO - 'LANG': 'en_US.UTF-8', 09:51:21 INFO - 'LANGUAGE': 'en_US:en', 09:51:21 INFO - 'LOGNAME': 'cltbld', 09:51:21 INFO - 'MAIL': '/var/mail/cltbld', 09:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:21 INFO - 'MOZ_NO_REMOTE': '1', 09:51:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:21 INFO - 'NO_EM_RESTART': '1', 09:51:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:21 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:21 INFO - 'SHELL': '/bin/bash', 09:51:21 INFO - 'SHLVL': '1', 09:51:21 INFO - 'TERM': 'linux', 09:51:21 INFO - 'TMOUT': '86400', 09:51:21 INFO - 'USER': 'cltbld', 09:51:21 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:21 INFO - '_': '/tools/buildbot/bin/python'} 09:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 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)) 09:51:21 INFO - Cleaning up... 09:51:21 INFO - Return code: 0 09:51:21 INFO - Running post-action listener: _resource_record_post_action 09:51:21 INFO - Running post-action listener: _start_resource_monitoring 09:51:21 INFO - Starting resource monitoring. 09:51:21 INFO - ##### 09:51:21 INFO - ##### Running install step. 09:51:21 INFO - ##### 09:51:21 INFO - Running pre-action listener: _resource_record_pre_action 09:51:21 INFO - Running main action method: install 09:51:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:51:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:51:21 INFO - Reading from file tmpfile_stdout 09:51:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:51:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:51:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:51:21 INFO - Reading from file tmpfile_stdout 09:51:21 INFO - Output received: 09:51:21 INFO - Usage: mozinstall [options] installer 09:51:21 INFO - Options: 09:51:21 INFO - -h, --help show this help message and exit 09:51:21 INFO - -d DEST, --destination=DEST 09:51:21 INFO - Directory to install application into. [default: 09:51:21 INFO - "/builds/slave/test-pgo"] 09:51:21 INFO - --app=APP Application being installed. [default: firefox] 09:51:21 INFO - mkdir: /builds/slave/test-pgo/build/application 09:51:21 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'] 09:51:21 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 09:51:36 INFO - Reading from file tmpfile_stdout 09:51:36 INFO - Output received: 09:51:36 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:51:36 INFO - Running post-action listener: _resource_record_post_action 09:51:36 INFO - ##### 09:51:36 INFO - ##### Running run-tests step. 09:51:36 INFO - ##### 09:51:36 INFO - Running pre-action listener: _resource_record_pre_action 09:51:36 INFO - Running main action method: run_tests 09:51:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:51:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:51:36 INFO - Python 2.7.3 09:51:36 INFO - Return code: 0 09:51:36 INFO - grabbing minidump binary from tooltool 09:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:36 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 0xd30d70>, '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 0xd31b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd31fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:51:36 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 09:51:36 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 09:51:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:51:36 INFO - Return code: 0 09:51:36 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:51:36 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:51:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:51:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:51:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:51:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:51:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost 09:51:36 INFO - Using env: {'DISPLAY': ':0', 09:51:36 INFO - 'HOME': '/home/cltbld', 09:51:36 INFO - 'LANG': 'en_US.UTF-8', 09:51:36 INFO - 'LANGUAGE': 'en_US:en', 09:51:36 INFO - 'LOGNAME': 'cltbld', 09:51:36 INFO - 'MAIL': '/var/mail/cltbld', 09:51:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:51:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:36 INFO - 'MOZ_NO_REMOTE': '1', 09:51:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:51:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:36 INFO - 'NO_EM_RESTART': '1', 09:51:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:51:36 INFO - 'PWD': '/builds/slave/test-pgo', 09:51:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:51:36 INFO - 'SHELL': '/bin/bash', 09:51:36 INFO - 'SHLVL': '1', 09:51:36 INFO - 'TERM': 'linux', 09:51:36 INFO - 'TMOUT': '86400', 09:51:36 INFO - 'USER': 'cltbld', 09:51:36 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177', 09:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:36 INFO - '_': '/tools/buildbot/bin/python'} 09:51:36 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost'] with output_timeout 3600 09:51:36 INFO - mozversion INFO | application_buildid: 20151123073219 09:51:36 INFO - mozversion INFO | application_changeset: d3d286102ba7f8801e9dfe12d534f49554ba50c0 09:51:36 INFO - mozversion INFO | application_display_name: Nightly 09:51:36 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:51:36 INFO - mozversion INFO | application_name: Firefox 09:51:36 INFO - mozversion INFO | application_remotingname: firefox 09:51:36 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:51:36 INFO - mozversion INFO | application_vendor: Mozilla 09:51:36 INFO - mozversion INFO | application_version: 45.0a1 09:51:36 INFO - mozversion INFO | platform_buildid: 20151123073219 09:51:36 INFO - mozversion INFO | platform_changeset: d3d286102ba7f8801e9dfe12d534f49554ba50c0 09:51:36 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:51:36 INFO - mozversion INFO | platform_version: 45.0a1 09:51:36 INFO - 2015-11-23 09:51:36,447 DEBUG : using testdate: 1448301096 09:51:36 INFO - 2015-11-23 09:51:36,447 DEBUG : actual date: 1448301096 09:51:36 INFO - 2015-11-23 09:51:36,495 INFO : starting webserver on 'localhost:40240' 09:51:36 INFO - 2015-11-23 09:51:36,496 INFO : Starting test suite talos-linux64-ix-031 09:51:36 INFO - 2015-11-23 09:51:36,496 INFO : Starting test dromaeo_css 09:51:36 INFO - 2015-11-23 09:51:36,496 DEBUG : operating with platform_type : linux_ 09:51:36 INFO - 2015-11-23 09:51:36,497 INFO : Initialising browser for dromaeo_css test... 09:51:36 INFO - 2015-11-23 09:51:36,513 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHYW_5S/profile http://localhost:40240/getInfo.html 09:51:41 INFO - 2015-11-23 09:51:41,206 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:51:41 INFO - 2015-11-23 09:51:41,206 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:51:41 INFO - 2015-11-23 09:51:41,206 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:51:41 INFO - 2015-11-23 09:51:41,206 DEBUG : BROWSER_OUTPUT: __metrics 09:51:41 INFO - 2015-11-23 09:51:41,303 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:51:42 INFO - 2015-11-23 09:51:42,683 INFO : Browser initialized. 09:51:42 INFO - 2015-11-23 09:51:42,684 INFO : Running cycle 1/1 for dromaeo_css test... 09:51:42 INFO - 2015-11-23 09:51:42,684 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHYW_5S/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:51:54 INFO - 2015-11-23 09:51:54,465 DEBUG : BROWSER_OUTPUT: RSS: Main: 140701696 09:51:54 INFO - 2015-11-23 09:51:54,465 DEBUG : BROWSER_OUTPUT: 09:52:46 INFO - 2015-11-23 09:52:46,044 DEBUG : BROWSER_OUTPUT: 1448301166041 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] 09:54:54 INFO - 2015-11-23 09:54:54,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40240/tests/dromaeo/cssquery-ext.html) 09:54:56 INFO - 2015-11-23 09:54:56,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 276414464 09:54:56 INFO - 2015-11-23 09:54:56,288 DEBUG : BROWSER_OUTPUT: 09:57:58 INFO - 2015-11-23 09:57:58,749 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/dromaeo/cssquery-ext.html (next: http://localhost:40240/tests/dromaeo/cssquery-jquery.html) 09:57:59 INFO - 2015-11-23 09:57:59,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 220323840 09:57:59 INFO - 2015-11-23 09:57:59,322 DEBUG : BROWSER_OUTPUT: 10:01:01 INFO - 2015-11-23 10:01:01,214 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40240/tests/dromaeo/cssquery-mootools.html) 10:01:01 INFO - 2015-11-23 10:01:01,795 DEBUG : BROWSER_OUTPUT: RSS: Main: 219316224 10:01:01 INFO - 2015-11-23 10:01:01,796 DEBUG : BROWSER_OUTPUT: 10:04:03 INFO - 2015-11-23 10:04:03,704 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40240/tests/dromaeo/cssquery-prototype.html) 10:04:04 INFO - 2015-11-23 10:04:04,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 203362304 10:04:04 INFO - 2015-11-23 10:04:04,285 DEBUG : BROWSER_OUTPUT: 10:07:06 INFO - 2015-11-23 10:07:06,745 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40240/tests/dromaeo/cssquery-yui.html) 10:07:07 INFO - 2015-11-23 10:07:07,332 DEBUG : BROWSER_OUTPUT: RSS: Main: 190734336 10:07:07 INFO - 2015-11-23 10:07:07,333 DEBUG : BROWSER_OUTPUT: 10:10:09 INFO - 2015-11-23 10:10:09,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/dromaeo/cssquery-yui.html (next: http://localhost:40240/tests/dromaeo/cssquery-dojo.html) 10:10:10 INFO - 2015-11-23 10:10:10,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 266973184 10:10:10 INFO - 2015-11-23 10:10:10,168 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,168 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:10:10 INFO - 2015-11-23 10:10:10,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:10:10 INFO - 2015-11-23 10:10:10,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:10:10 INFO - 2015-11-23 10:10:10,168 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:10:10 INFO - 2015-11-23 10:10:10,170 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3164.27221739;3214;3273.7708360414767;3274.4760838265865;3295.5551000614923;5336.199570064483;5427.918581221275;5487.945120548793;5516.338039435268;5566.109422492397;5351.57187425006;5442.809501667442;5445.128779395298;5473.343198816137;5542.584306177033;4987.600991920646;5038.622103342245;5049.520295571915;5074.594032477411;5102.36220472441;4867.659263851532;5088.847334579968;5096.79612815487;5135.43510213878;5166.405863325709;4946.010797840447;4999.875003124915;5005.1491246488185;5054.545090941833;5066.873328166788;4962.429320628138;4962.826301079481;5007.123753343187;5013.147764879979;5053.8231161909525;4940.802367905279;4979.900401991976;5008.724520151391;5031.572316353115;5033.471485494007;5177.378714554277;5268.789248430096;5284.128118860383;5305.7877684892555;5317.9468205317835;5935.40645935403;5952.166696662427;5953.13679516472;5963.165156878041;5964.880702385928;5718.313802343704;5910.1430292607765;5971.671558064305;5978.461938425563;5985.521158307325;5423.891522169574;5485.177223416519;5486.478784515464;5493.835184944418;5530.391656917735;5980.641161530322;5987;5998.730057147439;6009.969950150221;6014.248218972629;5554.416786237468;5667;5673.74468148926;5842.415758424208;5863.970680146657;5794.015017447045;5853.5317174625925;5854.502367298827;5858.765649374062;5861.9706901465215;5796.536277097822;5827.35899051104;5900.0854867494745;5910.674912879833;5948.613340132878;5932.2584676915385;5941.346451890288;5946.989011867993;5958.731857066442;5982.73077711504;4412.161689279027;4563.885902852455;4569.565891240327;4572.657050721209;4595.586397224266;3973.8013099345494;4023.1953609277675;4029.5567487576345;4067.471228740304;4074.7351422157467;5598.524125449301;5630;5672;5675.347335056521;5694.288213973254;5808.941910580925;5989.850253743692;6009.399060093956;6011.458968692838;6015.248093988252;5544.362398324244;5547.55619550435;5563.221149039137;5572.498475137257;5573.749181286852;5049.823256186016;5073.518015788494;5113.53978141969;5125.333706618116;5154.278401023859;6076.370825096164;6076.411268331831;6084.363354884847;6084.993425785769;6085.9295135822285;5946.762129514771;5946.970265148647;5948.375420580776;5957.285125784933;6009.609375390585;5732.111522713986;5864.67744274069;5881.147233651182;5956.106584012433;6037.396260373927;5810.82567522975;5851.824445266649;5859.355470898113;5862.413758624103;5879.353271140086;5950.166976623363;5981.461668449861;5985.5810093294795;5988.251468566429;5989.880202396015;5513.8345849624575;5610.354809196831;5613.803516877053;5652.123920792284;5771.394003629558;5829.795957141479;5830.329512106162;5830.416958304305;5840.474357307863;5846.619969701956;5726.541876649775;5732.226149469769;5734.283214598176;5737.08206686928;5739.483446489836;5587.525060369914;5626.2685850839125;5684.374718781013;5730.598858079894;5825.883482330245;5733.082706766897;5744.569157313134;5750.8849823002465;5753;5768.625039372428 10:10:10 INFO - 2015-11-23 10:10:10,173 DEBUG : BROWSER_OUTPUT: |1;ext.html;589.8409625086704;596.0552524249065;598.5451057196532;600.1747597054051;603.6498830678207;31874.362512749733;39523.80238098813;40145.39781903267;40354.192916141736;40439.39340909885;41788.58211417885;42098;42152.78923605377;42157.578424215746;42191.36712949308;7614.543127412346;7738.342240909529;8774.122587741207;8951.283897288235;8953.597088131017;10472.738181545446;10633.043026127647;10635.042846143846;10646;10684.626038088669;10393.584256629725;10551.683449496528;10558;10560.524776385042;10575.576976920915;7646.464747467679;7802.297793198582;10304.536295866666;10421.374717516974;10667.733306667318;10222.284440089197;10376.740581485448;10393.532291046846;10424.270301078928;10432.582696692123;38819.8059009706;39468.80265598682;39496.40755388672;39497;39575.208495829924;3218.6781321867625;3252.869885204589;3381.6618338165986;3519.560054993126;4116.670666346685;29553.556696649568;29954.55068173979;30024.8498757507;30108;30114;3217.7425805935704;3303.719183869374;3449.4653328734084;3461.5153878457036;3557.7331700122595;250878;251360;251505;251848;251931;54985;56203;57845;62198.024158067245;63400;58274.41725582775;58322.708386458646;58515.70742146262;58712;58717.41282587119;13719.862801372059;13739.450421983009;13810.447582096804;13811.861881381259;13823.723525529433;10617.575296988034;10633.361998280128;10672.252942293964;10676.893231067745;10688.786224275627;19060;19091.522711932314;19098.2360705573;19122.2351105957;19271.90364048171;7450.478466507401;7610.353119984753;7655.732049378245;7665.118511371152;7779.766607001686;942.6700654770775;942.7171848445439;957.5690939077303;958.5159494455255;1016.3190662256306;28088.29779255535;28109.71890281071;28144.577831332546;28588;28645;990.9454979976026;1049.506731836036;1066.6959916423782;1133.1274918313018;1146.9161642248162;14745.1890146043;14910.478133265284;15518.146501942276;15709.293081811345;16138.435154769526;317491;319667;320755;322196;323051;51289.487105129214;51291;51306.74346628243;51445.742771285906;51568;1168.9649310520697;1329.2556168545646;1336.245021562814;1339.9598012059653;1357.7963305504254;20675.79324206799;26779.834929742967;28929.42141157207;29084.272893176993;29493;47404.288935666686;47479.28781068217;47797.76101119472;47823.28265075957;47875.52124478711;272803;277797;277903;280230;280973;29828.254293642833;30071.09786706402;30323.754674345168;30617.387652247275;30754;35902.820485897406;36862.44706329461;37042.8147859259;37110;37184.81407592945;30722;31148.68851311458;31462.370752585277;31644.367112657157;31965.360692785547;31714.524282135324;32183.839080804446;32255.677443225268;34236;34923.47614785729;33552.49671254884;34327.141821454665;34394.14014649654;34510.48234276538;34542.65457345395;30634.693653063183;31237.68762312348;31810.204744881565;31836.204094896886;32006.199845004063;24556.877215613807;24614.138505152234;26129.346766330993;26622.87155249652;26774.661266936964 10:10:10 INFO - 2015-11-23 10:10:10,174 DEBUG : BROWSER_OUTPUT: |2;jquery.html;334.3313373253493;334.7757002808118;346.7208896838046;354.227783432118;354.8279084643948;13842.30788460578;14162.362693678784;16460.753088703663;16872.493825185233;18268.81731182688;14528.927355363208;15532.301046452909;16069.758953615705;16155.676886462265;16184.83815161848;5394.946050539504;5568.248286481314;5578.24693666356;5593.496585307322;5595.776168953257;4062.817173227197;4158.958410415902;5442.673439593637;5479.890402191953;5907.4683278505145;6002.489788367994;6026.939730602704;6030.939690603103;6079.331273559905;6089.543284253698;4950.207966725325;5096.108181068316;5109.080365534202;5117.360329958755;6013.48885344744;4803.831865884712;4889.682170658896;4910.312556242093;4927.5811556017425;5122.206058061007;11193.66419007431;11379.544818207261;11399.430028498542;11436.656900293005;14909.776353354708;2077.5325551750884;2098.5593025464673;2099.454141923096;2100.7479102507796;2153.967690484644;8371.16288371164;9155.633774649006;9655.710328690151;10927.235093543455;13070.673233169151;1713.5544758362914;1714.871384646144;1762.5769815244378;1813.2837529176034;1819.3086627081757;57676.134857976744;58813.70593147007;60539.697301513654;60919.69540152315;61460;9482.715518534455;9632.27757918159;9647.469389183661;9700.078492543196;9720.513974301257;12307.323097229739;12339.75320493603;12353.258804471761;12388.442520086617;12413.131080824436;6655.534112612071;6657.567258128206;6660.500462465237;6672.366125218194;6715.529912906148;6505.316941721146;6564.901526477107;7194.280571942869;7253.601051942193;7280.162781280114;3904.04350934023;4008.7594744315434;4037;4043.332850079704;4155.58444155588;1644.6710657868234;1668.3410053028986;1685.233218885404;1698;1711.7860267466567;404.14926579549626;915.1626261970223;924.7503174142944;930.8138372325562;1007.7673770165335;9619.134277914882;9716.562754676057;9812.460314682618;10065.647702330383;10102.444365559964;395.75067707344186;395.9566542161425;410.74739035492956;411.7591209142685;437.1366551061628;5344.893102137936;5516;5616.85957851057;5627.465390787868;5633.605647604711;501762;504045;508975.6473135046;512157;533465.9860714647;63569.682151588946;81416.59291703504;81993.59003205065;82895.585522072;83581;436.6484979591396;447.71346338342835;448.03001501749003;448.19101521752896;448.4775236849034;10099.04059114383;10142.49287535635;14202.78695819543;14239.786403203752;14980.250987450801;31536.68463315429;32540.349193015532;32766.508502372006;32835.50746738849;35834.4624830633;40673;47011;47088.52911470841;47125.29312060253;50430.49569504405;9097.272218222659;9227.35408521424;9253.352265341362;9255.213306868991;9306.953465232631;16357.691384689435;17064.744028839825;17069.061201633533;17452.650946981244;17527.649447011245;13911;14144.292785360896;14274.857251427353;14353.282335882956;14634;2719.3201699575106;13392.531261405804;13708.161714719894;14065.859341406454;14520.564383068524;14619.85380146185;14977.251137443302;15114.470993515175;15420.151891645619;15449;8764.430312029697;8959.596818143329;8997.28021758271;9531.38046027006;9533.570989305359;14516.709665806837;14711.55865324042;14775.113493190443;15143.167125807746;15263.542093737206 10:10:10 INFO - 2015-11-23 10:10:10,176 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1265.9303738294402;1680.7310830267156;1717.235830055625;1745.2757105801093;1746.7641868347778;6757.493188010901;7324.267573242673;7462.626868656559;7463.2163622819635;7469.925300746991;7448.217937116606;7505.887411688829;7534.585597792119;7540.132884718259;7550.546967181973;1917.7890432052463;1947.7760057593346;2195.3304242206123;2214.966775498369;2220.877851718155;2231.475603233239;2260.4009937366527;2260.8982595783227;2262.276071657062;2263.6830843681896;2152.709384233132;2187.639039558479;2205.1730601024615;2209.326155522565;2213.1589995801655;1885.481492589535;1916.1185854506944;2139.465133716571;2173.945651358713;2177.2488491470494;2055.2601063617085;2061.721667574873;2061.835053195741;2063.535704466498;2067.6898465230183;7363.337299643004;7465.477416580841;7473.813154671122;7476;7477.327040566321;4428.490723566799;4570.7257564546235;4573.405457290531;4581.244094724367;4582.518835522255;2881.5677648352707;2888.422315536901;2909.636295463067;2932.912012639603;3012.1264833198347;881.2861582118504;882.8852249207627;901.5627420700955;945.5555888732284;947.6398968392036;10510.31682940614;10516.158707303397;10519.105876000625;10558.78882422355;10567.26029177958;4020.2160578686876;4092.9795351023054;4130.401091841666;4186.28833098374;4192.685548583869;8984;9006.909930900607;9015.774605634911;9030.548472576345;9039.09609039104;4556.88607784808;4563.201439748031;4627.814887404466;4642.9535704643195;4648.814047438064;4248.87253382399;4253.149370125987;4254.957450425518;4255.319148936155;4257.361395790656;3572.499850020999;3590.174259920233;3597.6402359763815;3613.6928361089076;3625.9637403625625;1105.4528008635777;1158.8319693644544;1165.2251252916715;1173.636172786439;1176.923499972499;1277.201748906933;1297.9026573007063;1302.3227921480777;1318.6637407461037;1331.9134256273505;7269.127704675473;7332.816679582947;7347.08161479815;7364.521306115085;7375.004374409494;434.42004923426947;458.8322718680965;466.8202741944308;473.8673171511982;475.05701183151416;5698.430156984351;5703.914441283384;5784.421557844182;5786.855328616734;5824.242848429762;9167.495787731605;9416.858747118798;9440.95279523598;9457.574409151604;9459.90540094604;9036.8644470333;9229.630814767468;9256.352055356061;9333.206677432494;9344.065593440737;6915.135608048994;6932.896006559906;6963.268856770169;6983;7063.1524217094275;6913.723451061901;6943.6875340608785;6961.338672826073;6978.68595913175;6994.755183568551;8047.356211503183;8100.675972961016;8196.221358970888;8289.668413263402;8341.624626891926;8204.876926845982;8270.669173233004;8392.790180245544;8394.076651568445;8421.536815475207;6370.108184854032;6401.327860574573;6401.64790936503;6407.423331900151;6417.935820641734;7074.29257074309;7148.785536433916;7205.71177152929;7212.459065570209;7214.855702886018;6954.547954382949;6978.651067446506;6996.720131194696;7010.263922288086;7047.119110111236;6732.6297053660555;6776.051352810512;6815.284395138439;6842.486813489108;6955.686994085379;6615.338466153346;6672.165979252593;6674.766383176566;6676.365745254193;6682.63245521501;6288;6375.553711240169;6385.616862988236;6388;6399.616023038632;5895.587308888508;5896.321922978912;6439.8068057958335;6440.033994900802;6463.3213512580505 10:10:10 INFO - 2015-11-23 10:10:10,177 DEBUG : BROWSER_OUTPUT: |4;prototype.html;308.27555245173835;308.65430717596297;313.4827534567963;313.8650380336454;315.7205872802569;5991.161237426763;6052.092186172076;6056.576039677225;6082.148499210114;6090.878182436349;5999.850003749909;6002.339742628307;6005;6007.068904319837;6008.369121242272;4400.82396704133;4505.301678239878;4594.127115847995;4596.5403459653935;4597.218472859605;4497;4602.102589994953;4623.352730617717;4625.468071171807;4635.791389387468;4552.021315417184;4561.908761824761;4567.38340324057;4569.543045695421;4574.268117101265;4386.539413361599;4442.489113751928;4453.398791163184;4479.46246450428;4562.270036794113;3944.526656801202;3974.364101743707;3985.163115745697;3988.3020471417385;3989.5212574490956;5704.657720536739;5711.05767548354;5724.141378793214;5742.0238559445;5760.596758226926;5273.288106105665;5284.048871203182;5288.947110528884;5289.973550132263;5329.440408757102;6237.376262373772;6396.808095757135;6400.295967443624;6401.743930242785;6413.198350206224;4270.829166833323;4272.33778764292;4273.786310684484;4273.807678654468;4279.079997800457;6577.769778057745;6620.23867255267;6645.734170633217;6646;6647.401733843977;4272.166927449118;4366.19225443294;4371.978140109279;4373.322135069069;4389.253826849444;4421.425214722066;4425.690201685916;4427.955720442755;4429.025614364835;4430.62339701129;5207.895842083137;5213.635045546851;5214.921776173361;5222.791088356499;5226.503482169188;6435;6441.452476539453;6448.3229260927865;6448.355164483514;6448.903266451007;1546.1341648676553;1550.9457168999031;1566;1581.5097319830888;1584.7305957987169;1014.0873214107215;1021.1116328793848;1027.0961553832728;1041.5209003858158;1042.1610603464133;6239.563230573816;6241.563090583615;6242.500599951993;6242.531810114168;6249.281332646713;6619.205695316593;6623.933760662428;6625.503087268474;6626.105475760807;6627.10534077903;6087.026075827846;6203.844903877349;6223.657698826518;6225.221847269091;6232.501399887998;3587.0494319005484;3599.3881040223223;3605.5132557104457;3605.6935160511125;3616.8914932552066;6809.693563789641;6812.6934287957165;6814.807408703457;6820.727170913207;6827.1807383113355;6824.863502730017;6827.317268273133;6828.760993365208;6829.658517074126;6833.829154271183;6669.266380698119;6905.1713794345;6912.999999999899;6919.411849992706;6943.270956549591;6712.328767123248;6721.22705888829;6722.899156512558;6728.562643428161;6735.259121496533;6436.645984470899;6686.598804071771;6687.1975362956755;6687.933120668731;6697.497687673346;6863.416609588241;6865.41643960269;6875.724971001104;6877.3810357068105;6877.690503927235;4444.688871778944;4479.955200448084;4482.618977386958;4485.147821913826;4486.79809408584;6676.766313179015;6685.298043705341;6690.799276021728;6691.1636045494315;6695.732170713117;6362.109304697438;6484.448821850006;6486.156799616019;6495.090687303876;6500.934990650033;6453.1610890583925;6460.935390646221;6461.547691661539;6466.870662586816;6485.54601177913;6196.566240363311;6378.043293505825;6379.872402552016;6383.329750376143;6383.521235907233;6277.2153480814895;6386.61680299165;6392.552521323462;6430.228372595318;6512.08830763684;6257.937420625735;6275.717592708248;6287.46556542699;6333.303336632875;6338.841528961812 10:10:10 INFO - 2015-11-23 10:10:10,178 DEBUG : BROWSER_OUTPUT: |5;yui.html;741.3290971670638;824.4435006370705;835.4861760017587;836.3727204596553;852.9445586036909;12635.494580216791;12849.100562960597;12860.228386296829;12888.548900788473;12911.870881291195;12030.157888947777;12136.514539418435;12136.817947730791;12143.878561214386;12157.939210303959;667.5260564998856;685.3934268172653;790.3598085550693;809.5709274084729;825.3644693585935;820.5158956215831;820.7906983719143;827.6730691376903;827.8096037911284;828.9751307460785;847.7414388611471;856.618804631939;856.8414843253987;857.7212405968086;858.3605214115493;689.4243306838796;707.2079271216245;820.122468958218;846.0017179727919;849.4945507423073;626.9874602507924;627.6830200748593;627.6987046217795;628.8427893026743;630.0234636313695;12383.133180677267;12534.937325313405;12560.748785024249;12574.182678125984;12579.559715409916;12414;12962.740745185043;13014.479420823156;13144.474221031147;13336.466541338335;9797.706068817946;9831.213502919822;10181.083702466814;10227.130693891027;10329.07038366543;3513.7364697647777;3516.261585067139;3523.9295214095573;3534.7480318107405;3603.4774957631257;44412.3338149928;45222;45266;45291.320630190574;45344.77327613374;9900.257480688977;10113.342632728849;10172.69481915529;10529.947350263199;10621.73445663849;18066.638667226583;18090.728639070425;18101;18102;18105.7284140738;7325.816854578572;7361.521501102411;7367.742129025459;7370.594617295993;7582.62086895653;4344.218040752695;4452.109578084396;4509.481409637868;4596.724196548218;4634;3026.2585666511404;3093.334932989418;3099.178717639782;3100.860461279248;3103.1311232854837;526.8050821196182;528.0995901987103;547.7726743401523;548.9478499542616;552.3951273355669;655.2431941108027;656.037533298879;681.9011243369683;699.087994335492;701.235653138082;8696.695615653422;8815.074417186102;8826.161514656098;8856.822863542693;8981.281497480186;613.1691557938929;629.7732816186169;635.378343442371;646.1051443750346;667.2126890269528;3899.6100389960775;3912.158885839558;3982.880513584539;4114.732542384795;4333.826646934087;51512.48487515152;53000.46999530032;53290;53532.73233633807;54252.45747542553;46916;47902.760486197345;48509;48727.269090963666;49002.75498622484;46894;47041;47244.29133562999;47552.762236189286;48044;9060.184583387527;9073.591688373906;9087.454752714859;9171.220446262143;9233.215176710055;34866;35414.29171416609;35713.28573428569;35925;36155.63844361523;44751.55248447474;45028.32457513206;46421;48370.274445884046;48405.51594484151;11498;11637.301761894314;11673.533058677558;11804.704882377666;11822.34977076269;12282.280480732526;12296.508139674313;12563.93718031404;12649.914050933472;13400;11882.881171188177;12046.879531204575;12174.39128043612;12535.24788512692;12536.74926501483;11643.476043578228;11783.882161178279;11933.880661193278;11994.700132496757;12300;11745.29528228309;12013.10665591036;12132.514699412277;12173.939130304292;12600.306983116017;10171.745706357104;10353.896461035594;10395.662455105601;10434.843477347998;10519.78960420772;9413.70850150056;9450.621975121194;9832.213422926006;10128.696139115838;10141 10:10:10 INFO - 2015-11-23 10:10:10,178 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:10:10 INFO - 2015-11-23 10:10:10,178 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:10:10 INFO - 2015-11-23 10:10:10,178 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1151 10:10:10 INFO - 2015-11-23 10:10:10,178 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: __startTimestamp1448302210166__endTimestamp 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5472.24 Median:5675.35 stddev:612.25 (10.8%) stddev-sans-first:586.74 10:10:10 INFO - 2015-11-23 10:10:10,179 DEBUG : BROWSER_OUTPUT: Values: 3164.3 3214.0 3273.8 3274.5 3295.6 5336.2 5427.9 5487.9 5516.3 5566.1 5351.6 5442.8 5445.1 5473.3 5542.6 4987.6 5038.6 5049.5 5074.6 5102.4 4867.7 5088.8 5096.8 5135.4 5166.4 4946.0 4999.9 5005.1 5054.5 5066.9 4962.4 4962.8 5007.1 5013.1 5053.8 4940.8 4979.9 5008.7 5031.6 5033.5 5177.4 5268.8 5284.1 5305.8 5317.9 5935.4 5952.2 5953.1 5963.2 5964.9 5718.3 5910.1 5971.7 5978.5 5985.5 5423.9 5485.2 5486.5 5493.8 5530.4 5980.6 5987.0 5998.7 6010.0 6014.2 5554.4 5667.0 5673.7 5842.4 5864.0 5794.0 5853.5 5854.5 5858.8 5862.0 5796.5 5827.4 5900.1 5910.7 5948.6 5932.3 5941.3 5947.0 5958.7 5982.7 4412.2 4563.9 4569.6 4572.7 4595.6 3973.8 4023.2 4029.6 4067.5 4074.7 5598.5 5630.0 5672.0 5675.3 5694.3 5808.9 5989.9 6009.4 6011.5 6015.2 5544.4 5547.6 5563.2 5572.5 5573.7 5049.8 5073.5 5113.5 5125.3 5154.3 6076.4 6076.4 6084.4 6085.0 6085.9 5946.8 5947.0 5948.4 5957.3 6009.6 5732.1 5864.7 5881.1 5956.1 6037.4 5810.8 5851.8 5859.4 5862.4 5879.4 5950.2 5981.5 5985.6 5988.3 5989.9 5513.8 5610.4 5613.8 5652.1 5771.4 5829.8 5830.3 5830.4 5840.5 5846.6 5726.5 5732.2 5734.3 5737.1 5739.5 5587.5 5626.3 5684.4 5730.6 5825.9 5733.1 5744.6 5750.9 5753.0 5768.6 10:10:10 INFO - 2015-11-23 10:10:10,180 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,181 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45043.79 Median:174650.60 stddev:74372.83 (42.6%) stddev-sans-first:74506.57 10:10:10 INFO - 2015-11-23 10:10:10,181 DEBUG : BROWSER_OUTPUT: Values: 589.8 596.1 598.5 600.2 603.6 31874.4 39523.8 40145.4 40354.2 40439.4 41788.6 42098.0 42152.8 42157.6 42191.4 7614.5 7738.3 8774.1 8951.3 8953.6 10472.7 10633.0 10635.0 10646.0 10684.6 10393.6 10551.7 10558.0 10560.5 10575.6 7646.5 7802.3 10304.5 10421.4 10667.7 10222.3 10376.7 10393.5 10424.3 10432.6 38819.8 39468.8 39496.4 39497.0 39575.2 3218.7 3252.9 3381.7 3519.6 4116.7 29553.6 29954.6 30024.8 30108.0 30114.0 3217.7 3303.7 3449.5 3461.5 3557.7 250878.0 251360.0 251505.0 251848.0 251931.0 54985.0 56203.0 57845.0 62198.0 63400.0 58274.4 58322.7 58515.7 58712.0 58717.4 13719.9 13739.5 13810.4 13811.9 13823.7 10617.6 10633.4 10672.3 10676.9 10688.8 19060.0 19091.5 19098.2 19122.2 19271.9 7450.5 7610.4 7655.7 7665.1 7779.8 942.7 942.7 957.6 958.5 1016.3 28088.3 28109.7 28144.6 28588.0 28645.0 990.9 1049.5 1066.7 1133.1 1146.9 14745.2 14910.5 15518.1 15709.3 16138.4 317491.0 319667.0 320755.0 322196.0 323051.0 51289.5 51291.0 51306.7 51445.7 51568.0 1169.0 1329.3 1336.2 1340.0 1357.8 20675.8 26779.8 28929.4 29084.3 29493.0 47404.3 47479.3 47797.8 47823.3 47875.5 272803.0 277797.0 277903.0 280230.0 280973.0 29828.3 30071.1 30323.8 30617.4 30754.0 35902.8 36862.4 37042.8 37110.0 37184.8 30722.0 31148.7 31462.4 31644.4 31965.4 31714.5 32183.8 32255.7 34236.0 34923.5 33552.5 34327.1 34394.1 34510.5 34542.7 30634.7 31237.7 31810.2 31836.2 32006.2 24556.9 24614.1 26129.3 26622.9 26774.7 10:10:10 INFO - 2015-11-23 10:10:10,181 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,181 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27159.54 Median:2283.67 stddev:83856.24 (3672.0%) stddev-sans-first:84067.29 10:10:10 INFO - 2015-11-23 10:10:10,182 DEBUG : BROWSER_OUTPUT: Values: 334.3 334.8 346.7 354.2 354.8 13842.3 14162.4 16460.8 16872.5 18268.8 14528.9 15532.3 16069.8 16155.7 16184.8 5394.9 5568.2 5578.2 5593.5 5595.8 4062.8 4159.0 5442.7 5479.9 5907.5 6002.5 6026.9 6030.9 6079.3 6089.5 4950.2 5096.1 5109.1 5117.4 6013.5 4803.8 4889.7 4910.3 4927.6 5122.2 11193.7 11379.5 11399.4 11436.7 14909.8 2077.5 2098.6 2099.5 2100.7 2154.0 8371.2 9155.6 9655.7 10927.2 13070.7 1713.6 1714.9 1762.6 1813.3 1819.3 57676.1 58813.7 60539.7 60919.7 61460.0 9482.7 9632.3 9647.5 9700.1 9720.5 12307.3 12339.8 12353.3 12388.4 12413.1 6655.5 6657.6 6660.5 6672.4 6715.5 6505.3 6564.9 7194.3 7253.6 7280.2 3904.0 4008.8 4037.0 4043.3 4155.6 1644.7 1668.3 1685.2 1698.0 1711.8 404.1 915.2 924.8 930.8 1007.8 9619.1 9716.6 9812.5 10065.6 10102.4 395.8 396.0 410.7 411.8 437.1 5344.9 5516.0 5616.9 5627.5 5633.6 501762.0 504045.0 508975.6 512157.0 533466.0 63569.7 81416.6 81993.6 82895.6 83581.0 436.6 447.7 448.0 448.2 448.5 10099.0 10142.5 14202.8 14239.8 14980.3 31536.7 32540.3 32766.5 32835.5 35834.5 40673.0 47011.0 47088.5 47125.3 50430.5 9097.3 9227.4 9253.4 9255.2 9307.0 16357.7 17064.7 17069.1 17452.7 17527.6 13911.0 14144.3 14274.9 14353.3 14634.0 2719.3 13392.5 13708.2 14065.9 14520.6 14619.9 14977.3 15114.5 15420.2 15449.0 8764.4 8959.6 8997.3 9531.4 9533.6 14516.7 14711.6 14775.1 15143.2 15263.5 10:10:10 INFO - 2015-11-23 10:10:10,182 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5301.26 Median:6386.81 stddev:2796.68 (43.8%) stddev-sans-first:2788.08 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: Values: 1265.9 1680.7 1717.2 1745.3 1746.8 6757.5 7324.3 7462.6 7463.2 7469.9 7448.2 7505.9 7534.6 7540.1 7550.5 1917.8 1947.8 2195.3 2215.0 2220.9 2231.5 2260.4 2260.9 2262.3 2263.7 2152.7 2187.6 2205.2 2209.3 2213.2 1885.5 1916.1 2139.5 2173.9 2177.2 2055.3 2061.7 2061.8 2063.5 2067.7 7363.3 7465.5 7473.8 7476.0 7477.3 4428.5 4570.7 4573.4 4581.2 4582.5 2881.6 2888.4 2909.6 2932.9 3012.1 881.3 882.9 901.6 945.6 947.6 10510.3 10516.2 10519.1 10558.8 10567.3 4020.2 4093.0 4130.4 4186.3 4192.7 8984.0 9006.9 9015.8 9030.5 9039.1 4556.9 4563.2 4627.8 4643.0 4648.8 4248.9 4253.1 4255.0 4255.3 4257.4 3572.5 3590.2 3597.6 3613.7 3626.0 1105.5 1158.8 1165.2 1173.6 1176.9 1277.2 1297.9 1302.3 1318.7 1331.9 7269.1 7332.8 7347.1 7364.5 7375.0 434.4 458.8 466.8 473.9 475.1 5698.4 5703.9 5784.4 5786.9 5824.2 9167.5 9416.9 9441.0 9457.6 9459.9 9036.9 9229.6 9256.4 9333.2 9344.1 6915.1 6932.9 6963.3 6983.0 7063.2 6913.7 6943.7 6961.3 6978.7 6994.8 8047.4 8100.7 8196.2 8289.7 8341.6 8204.9 8270.7 8392.8 8394.1 8421.5 6370.1 6401.3 6401.6 6407.4 6417.9 7074.3 7148.8 7205.7 7212.5 7214.9 6954.5 6978.7 6996.7 7010.3 7047.1 6732.6 6776.1 6815.3 6842.5 6955.7 6615.3 6672.2 6674.8 6676.4 6682.6 6288.0 6375.6 6385.6 6388.0 6399.6 5895.6 5896.3 6439.8 6440.0 6463.3 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5344.38 Median:6143.72 stddev:1651.47 (26.9%) stddev-sans-first:1612.26 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: Values: 308.3 308.7 313.5 313.9 315.7 5991.2 6052.1 6056.6 6082.1 6090.9 5999.9 6002.3 6005.0 6007.1 6008.4 4400.8 4505.3 4594.1 4596.5 4597.2 4497.0 4602.1 4623.4 4625.5 4635.8 4552.0 4561.9 4567.4 4569.5 4574.3 4386.5 4442.5 4453.4 4479.5 4562.3 3944.5 3974.4 3985.2 3988.3 3989.5 5704.7 5711.1 5724.1 5742.0 5760.6 5273.3 5284.0 5288.9 5290.0 5329.4 6237.4 6396.8 6400.3 6401.7 6413.2 4270.8 4272.3 4273.8 4273.8 4279.1 6577.8 6620.2 6645.7 6646.0 6647.4 4272.2 4366.2 4372.0 4373.3 4389.3 4421.4 4425.7 4428.0 4429.0 4430.6 5207.9 5213.6 5214.9 5222.8 5226.5 6435.0 6441.5 6448.3 6448.4 6448.9 1546.1 1550.9 1566.0 1581.5 1584.7 1014.1 1021.1 1027.1 1041.5 1042.2 6239.6 6241.6 6242.5 6242.5 6249.3 6619.2 6623.9 6625.5 6626.1 6627.1 6087.0 6203.8 6223.7 6225.2 6232.5 3587.0 3599.4 3605.5 3605.7 3616.9 6809.7 6812.7 6814.8 6820.7 6827.2 6824.9 6827.3 6828.8 6829.7 6833.8 6669.3 6905.2 6913.0 6919.4 6943.3 6712.3 6721.2 6722.9 6728.6 6735.3 6436.6 6686.6 6687.2 6687.9 6697.5 6863.4 6865.4 6875.7 6877.4 6877.7 4444.7 4480.0 4482.6 4485.1 4486.8 6676.8 6685.3 6690.8 6691.2 6695.7 6362.1 6484.4 6486.2 6495.1 6500.9 6453.2 6460.9 6461.5 6466.9 6485.5 6196.6 6378.0 6379.9 6383.3 6383.5 6277.2 6386.6 6392.6 6430.2 6512.1 6257.9 6275.7 6287.5 6333.3 6338.8 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,183 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13687.34 Median:45244.00 stddev:15420.67 (34.1%) stddev-sans-first:15433.28 10:10:10 INFO - 2015-11-23 10:10:10,185 DEBUG : BROWSER_OUTPUT: Values: 741.3 824.4 835.5 836.4 852.9 12635.5 12849.1 12860.2 12888.5 12911.9 12030.2 12136.5 12136.8 12143.9 12157.9 667.5 685.4 790.4 809.6 825.4 820.5 820.8 827.7 827.8 829.0 847.7 856.6 856.8 857.7 858.4 689.4 707.2 820.1 846.0 849.5 627.0 627.7 627.7 628.8 630.0 12383.1 12534.9 12560.7 12574.2 12579.6 12414.0 12962.7 13014.5 13144.5 13336.5 9797.7 9831.2 10181.1 10227.1 10329.1 3513.7 3516.3 3523.9 3534.7 3603.5 44412.3 45222.0 45266.0 45291.3 45344.8 9900.3 10113.3 10172.7 10529.9 10621.7 18066.6 18090.7 18101.0 18102.0 18105.7 7325.8 7361.5 7367.7 7370.6 7582.6 4344.2 4452.1 4509.5 4596.7 4634.0 3026.3 3093.3 3099.2 3100.9 3103.1 526.8 528.1 547.8 548.9 552.4 655.2 656.0 681.9 699.1 701.2 8696.7 8815.1 8826.2 8856.8 8981.3 613.2 629.8 635.4 646.1 667.2 3899.6 3912.2 3982.9 4114.7 4333.8 51512.5 53000.5 53290.0 53532.7 54252.5 46916.0 47902.8 48509.0 48727.3 49002.8 46894.0 47041.0 47244.3 47552.8 48044.0 9060.2 9073.6 9087.5 9171.2 9233.2 34866.0 35414.3 35713.3 35925.0 36155.6 44751.6 45028.3 46421.0 48370.3 48405.5 11498.0 11637.3 11673.5 11804.7 11822.3 12282.3 12296.5 12563.9 12649.9 13400.0 11882.9 12046.9 12174.4 12535.2 12536.7 11643.5 11783.9 11933.9 11994.7 12300.0 11745.3 12013.1 12132.5 12173.9 12600.3 10171.7 10353.9 10395.7 10434.8 10519.8 9413.7 9450.6 9832.2 10128.7 10141.0 10:10:10 INFO - 2015-11-23 10:10:10,185 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:10:10 INFO - 2015-11-23 10:10:10,185 DEBUG : BROWSER_OUTPUT: 10:10:10 INFO - 2015-11-23 10:10:10,861 INFO : Browser exited with error code: 0 10:10:10 INFO - 2015-11-23 10:10:10,878 INFO : Completed test dromaeo_css (00:18:34) 10:10:10 INFO - 2015-11-23 10:10:10,878 INFO : Starting test kraken 10:10:10 INFO - 2015-11-23 10:10:10,878 DEBUG : operating with platform_type : linux_ 10:10:10 INFO - 2015-11-23 10:10:10,878 INFO : Initialising browser for kraken test... 10:10:10 INFO - 2015-11-23 10:10:10,884 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp45YK4r/profile http://localhost:40240/getInfo.html 10:10:14 INFO - 2015-11-23 10:10:14,578 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:10:14 INFO - 2015-11-23 10:10:14,578 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:10:14 INFO - 2015-11-23 10:10:14,578 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:10:14 INFO - 2015-11-23 10:10:14,578 DEBUG : BROWSER_OUTPUT: __metrics 10:10:14 INFO - 2015-11-23 10:10:14,720 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:10:15 INFO - 2015-11-23 10:10:15,855 INFO : Browser initialized. 10:10:15 INFO - 2015-11-23 10:10:15,856 INFO : Running cycle 1/1 for kraken test... 10:10:15 INFO - 2015-11-23 10:10:15,856 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp45YK4r/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:10:27 INFO - 2015-11-23 10:10:27,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 140312576 10:10:27 INFO - 2015-11-23 10:10:27,640 DEBUG : BROWSER_OUTPUT: 10:10:30 INFO - 2015-11-23 10:10:30,098 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40240/tests/kraken/driver.html?testName=audio-beat-detection) 10:10:30 INFO - 2015-11-23 10:10:30,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 179511296 10:10:30 INFO - 2015-11-23 10:10:30,680 DEBUG : BROWSER_OUTPUT: 10:10:33 INFO - 2015-11-23 10:10:33,277 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40240/tests/kraken/driver.html?testName=audio-dft) 10:10:33 INFO - 2015-11-23 10:10:33,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 169140224 10:10:33 INFO - 2015-11-23 10:10:33,859 DEBUG : BROWSER_OUTPUT: 10:10:36 INFO - 2015-11-23 10:10:36,741 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40240/tests/kraken/driver.html?testName=audio-fft) 10:10:37 INFO - 2015-11-23 10:10:37,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 152551424 10:10:37 INFO - 2015-11-23 10:10:37,303 DEBUG : BROWSER_OUTPUT: 10:10:39 INFO - 2015-11-23 10:10:39,266 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40240/tests/kraken/driver.html?testName=audio-oscillator) 10:10:39 INFO - 2015-11-23 10:10:39,840 DEBUG : BROWSER_OUTPUT: RSS: Main: 154120192 10:10:39 INFO - 2015-11-23 10:10:39,840 DEBUG : BROWSER_OUTPUT: 10:10:42 INFO - 2015-11-23 10:10:42,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:10:42 INFO - 2015-11-23 10:10:42,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 156270592 10:10:42 INFO - 2015-11-23 10:10:42,614 DEBUG : BROWSER_OUTPUT: 10:10:45 INFO - 2015-11-23 10:10:45,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40240/tests/kraken/driver.html?testName=imaging-darkroom) 10:10:46 INFO - 2015-11-23 10:10:46,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 157704192 10:10:46 INFO - 2015-11-23 10:10:46,076 DEBUG : BROWSER_OUTPUT: 10:10:49 INFO - 2015-11-23 10:10:49,440 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40240/tests/kraken/driver.html?testName=imaging-desaturate) 10:10:50 INFO - 2015-11-23 10:10:50,014 DEBUG : BROWSER_OUTPUT: RSS: Main: 156463104 10:10:50 INFO - 2015-11-23 10:10:50,014 DEBUG : BROWSER_OUTPUT: 10:10:52 INFO - 2015-11-23 10:10:52,792 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40240/tests/kraken/driver.html?testName=json-parse-financial) 10:10:53 INFO - 2015-11-23 10:10:53,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 157802496 10:10:53 INFO - 2015-11-23 10:10:53,361 DEBUG : BROWSER_OUTPUT: 10:10:54 INFO - 2015-11-23 10:10:54,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:10:55 INFO - 2015-11-23 10:10:55,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 167510016 10:10:55 INFO - 2015-11-23 10:10:55,498 DEBUG : BROWSER_OUTPUT: 10:10:57 INFO - 2015-11-23 10:10:57,329 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:10:57 INFO - 2015-11-23 10:10:57,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 166027264 10:10:57 INFO - 2015-11-23 10:10:57,922 DEBUG : BROWSER_OUTPUT: 10:10:59 INFO - 2015-11-23 10:10:59,994 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:11:00 INFO - 2015-11-23 10:11:00,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 174923776 10:11:00 INFO - 2015-11-23 10:11:00,560 DEBUG : BROWSER_OUTPUT: 10:11:03 INFO - 2015-11-23 10:11:03,000 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:11:03 INFO - 2015-11-23 10:11:03,587 DEBUG : BROWSER_OUTPUT: RSS: Main: 179822592 10:11:03 INFO - 2015-11-23 10:11:03,588 DEBUG : BROWSER_OUTPUT: 10:11:06 INFO - 2015-11-23 10:11:06,195 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:11:06 INFO - 2015-11-23 10:11:06,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 179048448 10:11:06 INFO - 2015-11-23 10:11:06,764 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,420 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40240/tests/kraken/driver.html?testName=ai-astar) 10:11:08 INFO - 2015-11-23 10:11:08,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 238555136 10:11:08 INFO - 2015-11-23 10:11:08,670 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,671 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:11:08 INFO - 2015-11-23 10:11:08,672 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:11:08 INFO - 2015-11-23 10:11:08,672 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:11:08 INFO - 2015-11-23 10:11:08,673 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:11:08 INFO - 2015-11-23 10:11:08,673 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;108;102;109;109;110;99;111;108;100 10:11:08 INFO - 2015-11-23 10:11:08,673 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;131;158;164;125;154;155;125;174;127 10:11:08 INFO - 2015-11-23 10:11:08,673 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;159;159;159;159;160;161;162;161 10:11:08 INFO - 2015-11-23 10:11:08,673 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;85;91;71;71;72;104;91;71 10:11:08 INFO - 2015-11-23 10:11:08,673 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;137;92;93;93;137;93;93;131 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;146;145;146;145;145;146;146 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;96;96;96;95;96;95;95 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;61;61;61;60;61;60;60;62;59 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;69;70;70;70;70;68;69;69 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;83;85;83;91;82;83;84;93;85 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;123;119;114;121;125;129;119;128;125 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;150;162;151;148;151;158;150;151;149;144 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;67;69;67;68;66;66;67;68;67 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,928 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: __startTimestamp1448302268671__endTimestamp 10:11:08 INFO - 2015-11-23 10:11:08,674 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.40 Median:109.00 stddev:4.35 (4.0%) stddev-sans-first:4.58 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: Values: 108.0 108.0 102.0 109.0 109.0 110.0 99.0 111.0 108.0 100.0 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.40 Median:154.50 stddev:17.91 (11.6%) stddev-sans-first:18.92 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: Values: 151.0 131.0 158.0 164.0 125.0 154.0 155.0 125.0 174.0 127.0 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 159.0 159.0 159.0 159.0 160.0 161.0 162.0 161.0 10:11:08 INFO - 2015-11-23 10:11:08,676 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.70 Median:71.50 stddev:12.16 (17.0%) stddev-sans-first:12.38 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 85.0 91.0 71.0 71.0 72.0 104.0 91.0 71.0 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:92.50 stddev:20.56 (22.2%) stddev-sans-first:21.24 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 137.0 92.0 93.0 93.0 137.0 93.0 93.0 131.0 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 146.0 145.0 146.0 145.0 145.0 146.0 146.0 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:11:08 INFO - 2015-11-23 10:11:08,678 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:60.50 Median:61.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: Values: 60.0 61.0 61.0 61.0 60.0 61.0 60.0 60.0 62.0 59.0 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:0.79 (1.1%) stddev-sans-first:0.71 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 69.0 70.0 70.0 70.0 70.0 68.0 69.0 69.0 10:11:08 INFO - 2015-11-23 10:11:08,679 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.50 stddev:3.81 (4.5%) stddev-sans-first:3.88 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: Values: 82.0 83.0 85.0 83.0 91.0 82.0 83.0 84.0 93.0 85.0 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.70 Median:124.50 stddev:4.55 (3.7%) stddev-sans-first:4.80 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: Values: 124.0 123.0 119.0 114.0 121.0 125.0 129.0 119.0 128.0 125.0 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.40 Median:151.00 stddev:5.08 (3.4%) stddev-sans-first:5.36 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: Values: 150.0 162.0 151.0 148.0 151.0 158.0 150.0 151.0 149.0 144.0 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.95 (1.4%) stddev-sans-first:0.97 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 69.0 67.0 68.0 66.0 66.0 67.0 68.0 67.0 10:11:08 INFO - 2015-11-23 10:11:08,680 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:11:08 INFO - 2015-11-23 10:11:08,681 DEBUG : BROWSER_OUTPUT: 10:11:09 INFO - 2015-11-23 10:11:09,569 INFO : Browser exited with error code: 0 10:11:09 INFO - 2015-11-23 10:11:09,623 INFO : Completed test kraken (00:00:58) 10:11:09 INFO - 2015-11-23 10:11:09,623 INFO : Starting test v8_7 10:11:09 INFO - 2015-11-23 10:11:09,623 DEBUG : operating with platform_type : linux_ 10:11:09 INFO - 2015-11-23 10:11:09,623 INFO : Initialising browser for v8_7 test... 10:11:09 INFO - 2015-11-23 10:11:09,629 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8rwdUw/profile http://localhost:40240/getInfo.html 10:11:13 INFO - 2015-11-23 10:11:13,356 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:11:13 INFO - 2015-11-23 10:11:13,356 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:11:13 INFO - 2015-11-23 10:11:13,356 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:11:13 INFO - 2015-11-23 10:11:13,356 DEBUG : BROWSER_OUTPUT: __metrics 10:11:13 INFO - 2015-11-23 10:11:13,432 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:14 INFO - 2015-11-23 10:11:14,797 INFO : Browser initialized. 10:11:14 INFO - 2015-11-23 10:11:14,798 INFO : Running cycle 1/1 for v8_7 test... 10:11:14 INFO - 2015-11-23 10:11:14,798 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8rwdUw/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:11:26 INFO - 2015-11-23 10:11:26,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 140083200 10:11:26 INFO - 2015-11-23 10:11:26,577 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,031 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40240/tests/v8_7/run.html (next: http://localhost:40240/tests/v8_7/run.html) 10:11:48 INFO - 2015-11-23 10:11:48,281 DEBUG : BROWSER_OUTPUT: RSS: Main: 167911424 10:11:48 INFO - 2015-11-23 10:11:48,282 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,282 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:11:48 INFO - 2015-11-23 10:11:48,282 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:11:48 INFO - 2015-11-23 10:11:48,282 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:11:48 INFO - 2015-11-23 10:11:48,282 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:11:48 INFO - 2015-11-23 10:11:48,282 DEBUG : BROWSER_OUTPUT: |0;Richards;184.46781036709095 10:11:48 INFO - 2015-11-23 10:11:48,283 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.5903872348706 10:11:48 INFO - 2015-11-23 10:11:48,283 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.7684449489217 10:11:48 INFO - 2015-11-23 10:11:48,283 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4995.024875621891 10:11:48 INFO - 2015-11-23 10:11:48,283 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1962.7450980392157 10:11:48 INFO - 2015-11-23 10:11:48,283 DEBUG : BROWSER_OUTPUT: |5;Earley;767.4597083653108 10:11:48 INFO - 2015-11-23 10:11:48,283 DEBUG : BROWSER_OUTPUT: |6;Boyer;12292.682926829268 10:11:48 INFO - 2015-11-23 10:11:48,284 DEBUG : BROWSER_OUTPUT: |7;RegExp;25500 10:11:48 INFO - 2015-11-23 10:11:48,284 DEBUG : BROWSER_OUTPUT: |8;Splay;560.5381165919282 10:11:48 INFO - 2015-11-23 10:11:48,284 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4864.077669902913 10:11:48 INFO - 2015-11-23 10:11:48,284 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:11:48 INFO - 2015-11-23 10:11:48,284 DEBUG : BROWSER_OUTPUT: __startTimestamp1448302308279__endTimestamp 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:184.47 Median:184.47 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: Values: 184.5 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,285 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.59 Median:194.59 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,286 DEBUG : BROWSER_OUTPUT: Values: 194.6 10:11:48 INFO - 2015-11-23 10:11:48,286 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,286 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.77 Median:283.77 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,286 DEBUG : BROWSER_OUTPUT: Values: 283.8 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4995.02 Median:4995.02 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: Values: 4995.0 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1962.75 Median:1962.75 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: Values: 1962.7 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:767.46 Median:767.46 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: Values: 767.5 10:11:48 INFO - 2015-11-23 10:11:48,287 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12292.68 Median:12292.68 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: Values: 12292.7 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25500.00 Median:25500.00 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: Values: 25500.0 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:560.54 Median:560.54 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: Values: 560.5 10:11:48 INFO - 2015-11-23 10:11:48,288 DEBUG : BROWSER_OUTPUT: 10:11:48 INFO - 2015-11-23 10:11:48,289 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4864.08 Median:4864.08 stddev:0.00 (0.0%) 10:11:48 INFO - 2015-11-23 10:11:48,289 DEBUG : BROWSER_OUTPUT: Values: 4864.1 10:11:48 INFO - 2015-11-23 10:11:48,289 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:11:48 INFO - 2015-11-23 10:11:48,289 DEBUG : BROWSER_OUTPUT: 10:11:49 INFO - 2015-11-23 10:11:49,177 INFO : Browser exited with error code: 0 10:11:49 INFO - 2015-11-23 10:11:49,189 INFO : Completed test v8_7 (00:00:39) 10:11:49 INFO - 2015-11-23 10:11:49,252 INFO : Completed test suite (00:20:12) 10:11:49 INFO - 2015-11-23 10:11:49,252 DEBUG : Working with test: dromaeo_css 10:11:49 INFO - 2015-11-23 10:11:49,252 DEBUG : Generating results file: dromaeo_css 10:11:49 INFO - 2015-11-23 10:11:49,253 DEBUG : Working with test: kraken 10:11:49 INFO - 2015-11-23 10:11:49,254 DEBUG : Generating results file: kraken 10:11:49 INFO - 2015-11-23 10:11:49,254 INFO : javascript benchmark 10:11:49 INFO - 2015-11-23 10:11:49,254 DEBUG : Working with test: v8_7 10:11:49 INFO - 2015-11-23 10:11:49,254 DEBUG : Generating results file: v8_7 10:11:49 INFO - 2015-11-23 10:11:49,254 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:11:49 INFO - 2015-11-23 10:11:49,308 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 10:11:49 INFO - 2015-11-23 10:11:49,308 DEBUG : process_Request line: dromaeo_css 6816.20 graph.html#tests=[[72,1,35]] 10:11:49 INFO - 2015-11-23 10:11:49,308 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:11:49 INFO - 2015-11-23 10:11:49,336 DEBUG : process_Request line: kraken 1504.30 graph.html#tests=[[232,1,35]] 10:11:49 INFO - 2015-11-23 10:11:49,336 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:11:49 INFO - 2015-11-23 10:11:49,450 DEBUG : process_Request line: v8_7 19848.50 graph.html#tests=[[230,1,35]] 10:11:49 INFO - 2015-11-23 10:11:49,451 INFO : javascript benchmark 10:11:49 INFO - 2015-11-23 10:11:49,455 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3164.27221739, 3214.0, 3273.7708360414767, 3274.4760838265865, 3295.5551000614923, 5336.199570064483, 5427.918581221275, 5487.945120548793, 5516.338039435268, 5566.109422492397, 5351.57187425006, 5442.809501667442, 5445.128779395298, 5473.343198816137, 5542.584306177033, 4987.600991920646, 5038.622103342245, 5049.520295571915, 5074.594032477411, 5102.36220472441, 4867.659263851532, 5088.847334579968, 5096.79612815487, 5135.43510213878, 5166.405863325709, 4946.010797840447, 4999.875003124915, 5005.1491246488185, 5054.545090941833, 5066.873328166788, 4962.429320628138, 4962.826301079481, 5007.123753343187, 5013.147764879979, 5053.8231161909525, 4940.802367905279, 4979.900401991976, 5008.724520151391, 5031.572316353115, 5033.471485494007, 5177.378714554277, 5268.789248430096, 5284.128118860383, 5305.7877684892555, 5317.9468205317835, 5935.40645935403, 5952.166696662427, 5953.13679516472, 5963.165156878041, 5964.880702385928, 5718.313802343704, 5910.1430292607765, 5971.671558064305, 5978.461938425563, 5985.521158307325, 5423.891522169574, 5485.177223416519, 5486.478784515464, 5493.835184944418, 5530.391656917735, 5980.641161530322, 5987.0, 5998.730057147439, 6009.969950150221, 6014.248218972629, 5554.416786237468, 5667.0, 5673.74468148926, 5842.415758424208, 5863.970680146657, 5794.015017447045, 5853.5317174625925, 5854.502367298827, 5858.765649374062, 5861.9706901465215, 5796.536277097822, 5827.35899051104, 5900.0854867494745, 5910.674912879833, 5948.613340132878, 5932.2584676915385, 5941.346451890288, 5946.989011867993, 5958.731857066442, 5982.73077711504, 4412.161689279027, 4563.885902852455, 4569.565891240327, 4572.657050721209, 4595.586397224266, 3973.8013099345494, 4023.1953609277675, 4029.5567487576345, 4067.471228740304, 4074.7351422157467, 5598.524125449301, 5630.0, 5672.0, 5675.347335056521, 5694.288213973254, 5808.941910580925, 5989.850253743692, 6009.399060093956, 6011.458968692838, 6015.248093988252, 5544.362398324244, 5547.55619550435, 5563.221149039137, 5572.498475137257, 5573.749181286852, 5049.823256186016, 5073.518015788494, 5113.53978141969, 5125.333706618116, 5154.278401023859, 6076.370825096164, 6076.411268331831, 6084.363354884847, 6084.993425785769, 6085.9295135822285, 5946.762129514771, 5946.970265148647, 5948.375420580776, 5957.285125784933, 6009.609375390585, 5732.111522713986, 5864.67744274069, 5881.147233651182, 5956.106584012433, 6037.396260373927, 5810.82567522975, 5851.824445266649, 5859.355470898113, 5862.413758624103, 5879.353271140086, 5950.166976623363, 5981.461668449861, 5985.5810093294795, 5988.251468566429, 5989.880202396015, 5513.8345849624575, 5610.354809196831, 5613.803516877053, 5652.123920792284, 5771.394003629558, 5829.795957141479, 5830.329512106162, 5830.416958304305, 5840.474357307863, 5846.619969701956, 5726.541876649775, 5732.226149469769, 5734.283214598176, 5737.08206686928, 5739.483446489836, 5587.525060369914, 5626.2685850839125, 5684.374718781013, 5730.598858079894, 5825.883482330245, 5733.082706766897, 5744.569157313134, 5750.8849823002465, 5753.0, 5768.625039372428], "unit": "ms", "name": "dojo.html", "value": 5431.907092458106}, {"lowerIsBetter": false, "replicates": [589.8409625086704, 596.0552524249065, 598.5451057196532, 600.1747597054051, 603.6498830678207, 31874.362512749733, 39523.80238098813, 40145.39781903267, 40354.192916141736, 40439.39340909885, 41788.58211417885, 42098.0, 42152.78923605377, 42157.578424215746, 42191.36712949308, 7614.543127412346, 7738.342240909529, 8774.122587741207, 8951.283897288235, 8953.597088131017, 10472.738181545446, 10633.043026127647, 10635.042846143846, 10646.0, 10684.626038088669, 10393.584256629725, 10551.683449496528, 10558.0, 10560.524776385042, 10575.576976920915, 7646.464747467679, 7802.297793198582, 10304.536295866666, 10421.374717516974, 10667.733306667318, 10222.284440089197, 10376.740581485448, 10393.532291046846, 10424.270301078928, 10432.582696692123, 38819.8059009706, 39468.80265598682, 39496.40755388672, 39497.0, 39575.208495829924, 3218.6781321867625, 3252.869885204589, 3381.6618338165986, 3519.560054993126, 4116.670666346685, 29553.556696649568, 29954.55068173979, 30024.8498757507, 30108.0, 30114.0, 3217.7425805935704, 3303.719183869374, 3449.4653328734084, 3461.5153878457036, 3557.7331700122595, 250878.0, 251360.0, 251505.0, 251848.0, 251931.0, 54985.0, 56203.0, 57845.0, 62198.024158067245, 63400.0, 58274.41725582775, 58322.708386458646, 58515.70742146262, 58712.0, 58717.41282587119, 13719.862801372059, 13739.450421983009, 13810.447582096804, 13811.861881381259, 13823.723525529433, 10617.575296988034, 10633.361998280128, 10672.252942293964, 10676.893231067745, 10688.786224275627, 19060.0, 19091.522711932314, 19098.2360705573, 19122.2351105957, 19271.90364048171, 7450.478466507401, 7610.353119984753, 7655.732049378245, 7665.118511371152, 7779.766607001686, 942.6700654770775, 942.7171848445439, 957.5690939077303, 958.5159494455255, 1016.3190662256306, 28088.29779255535, 28109.71890281071, 28144.577831332546, 28588.0, 28645.0, 990.9454979976026, 1049.506731836036, 1066.6959916423782, 1133.1274918313018, 1146.9161642248162, 14745.1890146043, 14910.478133265284, 15518.146501942276, 15709.293081811345, 16138.435154769526, 317491.0, 319667.0, 320755.0, 322196.0, 323051.0, 51289.487105129214, 51291.0, 51306.74346628243, 51445.742771285906, 51568.0, 1168.9649310520697, 1329.2556168545646, 1336.245021562814, 1339.9598012059653, 1357.7963305504254, 20675.79324206799, 26779.834929742967, 28929.42141157207, 29084.272893176993, 29493.0, 47404.288935666686, 47479.28781068217, 47797.76101119472, 47823.28265075957, 47875.52124478711, 272803.0, 277797.0, 277903.0, 280230.0, 280973.0, 29828.254293642833, 30071.09786706402, 30323.754674345168, 30617.387652247275, 30754.0, 35902.820485897406, 36862.44706329461, 37042.8147859259, 37110.0, 37184.81407592945, 30722.0, 31148.68851311458, 31462.370752585277, 31644.367112657157, 31965.360692785547, 31714.524282135324, 32183.839080804446, 32255.677443225268, 34236.0, 34923.47614785729, 33552.49671254884, 34327.141821454665, 34394.14014649654, 34510.48234276538, 34542.65457345395, 30634.693653063183, 31237.68762312348, 31810.204744881565, 31836.204094896886, 32006.199845004063, 24556.877215613807, 24614.138505152234, 26129.346766330993, 26622.87155249652, 26774.661266936964], "unit": "ms", "name": "ext.html", "value": 18259.803936683456}, {"lowerIsBetter": false, "replicates": [334.3313373253493, 334.7757002808118, 346.7208896838046, 354.227783432118, 354.8279084643948, 13842.30788460578, 14162.362693678784, 16460.753088703663, 16872.493825185233, 18268.81731182688, 14528.927355363208, 15532.301046452909, 16069.758953615705, 16155.676886462265, 16184.83815161848, 5394.946050539504, 5568.248286481314, 5578.24693666356, 5593.496585307322, 5595.776168953257, 4062.817173227197, 4158.958410415902, 5442.673439593637, 5479.890402191953, 5907.4683278505145, 6002.489788367994, 6026.939730602704, 6030.939690603103, 6079.331273559905, 6089.543284253698, 4950.207966725325, 5096.108181068316, 5109.080365534202, 5117.360329958755, 6013.48885344744, 4803.831865884712, 4889.682170658896, 4910.312556242093, 4927.5811556017425, 5122.206058061007, 11193.66419007431, 11379.544818207261, 11399.430028498542, 11436.656900293005, 14909.776353354708, 2077.5325551750884, 2098.5593025464673, 2099.454141923096, 2100.7479102507796, 2153.967690484644, 8371.16288371164, 9155.633774649006, 9655.710328690151, 10927.235093543455, 13070.673233169151, 1713.5544758362914, 1714.871384646144, 1762.5769815244378, 1813.2837529176034, 1819.3086627081757, 57676.134857976744, 58813.70593147007, 60539.697301513654, 60919.69540152315, 61460.0, 9482.715518534455, 9632.27757918159, 9647.469389183661, 9700.078492543196, 9720.513974301257, 12307.323097229739, 12339.75320493603, 12353.258804471761, 12388.442520086617, 12413.131080824436, 6655.534112612071, 6657.567258128206, 6660.500462465237, 6672.366125218194, 6715.529912906148, 6505.316941721146, 6564.901526477107, 7194.280571942869, 7253.601051942193, 7280.162781280114, 3904.04350934023, 4008.7594744315434, 4037.0, 4043.332850079704, 4155.58444155588, 1644.6710657868234, 1668.3410053028986, 1685.233218885404, 1698.0, 1711.7860267466567, 404.14926579549626, 915.1626261970223, 924.7503174142944, 930.8138372325562, 1007.7673770165335, 9619.134277914882, 9716.562754676057, 9812.460314682618, 10065.647702330383, 10102.444365559964, 395.75067707344186, 395.9566542161425, 410.74739035492956, 411.7591209142685, 437.1366551061628, 5344.893102137936, 5516.0, 5616.85957851057, 5627.465390787868, 5633.605647604711, 501762.0, 504045.0, 508975.6473135046, 512157.0, 533465.9860714647, 63569.682151588946, 81416.59291703504, 81993.59003205065, 82895.585522072, 83581.0, 436.6484979591396, 447.71346338342835, 448.03001501749003, 448.19101521752896, 448.4775236849034, 10099.04059114383, 10142.49287535635, 14202.78695819543, 14239.786403203752, 14980.250987450801, 31536.68463315429, 32540.349193015532, 32766.508502372006, 32835.50746738849, 35834.4624830633, 40673.0, 47011.0, 47088.52911470841, 47125.29312060253, 50430.49569504405, 9097.272218222659, 9227.35408521424, 9253.352265341362, 9255.213306868991, 9306.953465232631, 16357.691384689435, 17064.744028839825, 17069.061201633533, 17452.650946981244, 17527.649447011245, 13911.0, 14144.292785360896, 14274.857251427353, 14353.282335882956, 14634.0, 2719.3201699575106, 13392.531261405804, 13708.161714719894, 14065.859341406454, 14520.564383068524, 14619.85380146185, 14977.251137443302, 15114.470993515175, 15420.151891645619, 15449.0, 8764.430312029697, 8959.596818143329, 8997.28021758271, 9531.38046027006, 9533.570989305359, 14516.709665806837, 14711.55865324042, 14775.113493190443, 15143.167125807746, 15263.542093737206], "unit": "ms", "name": "jquery.html", "value": 7814.659487713478}, {"lowerIsBetter": false, "replicates": [1265.9303738294402, 1680.7310830267156, 1717.235830055625, 1745.2757105801093, 1746.7641868347778, 6757.493188010901, 7324.267573242673, 7462.626868656559, 7463.2163622819635, 7469.925300746991, 7448.217937116606, 7505.887411688829, 7534.585597792119, 7540.132884718259, 7550.546967181973, 1917.7890432052463, 1947.7760057593346, 2195.3304242206123, 2214.966775498369, 2220.877851718155, 2231.475603233239, 2260.4009937366527, 2260.8982595783227, 2262.276071657062, 2263.6830843681896, 2152.709384233132, 2187.639039558479, 2205.1730601024615, 2209.326155522565, 2213.1589995801655, 1885.481492589535, 1916.1185854506944, 2139.465133716571, 2173.945651358713, 2177.2488491470494, 2055.2601063617085, 2061.721667574873, 2061.835053195741, 2063.535704466498, 2067.6898465230183, 7363.337299643004, 7465.477416580841, 7473.813154671122, 7476.0, 7477.327040566321, 4428.490723566799, 4570.7257564546235, 4573.405457290531, 4581.244094724367, 4582.518835522255, 2881.5677648352707, 2888.422315536901, 2909.636295463067, 2932.912012639603, 3012.1264833198347, 881.2861582118504, 882.8852249207627, 901.5627420700955, 945.5555888732284, 947.6398968392036, 10510.31682940614, 10516.158707303397, 10519.105876000625, 10558.78882422355, 10567.26029177958, 4020.2160578686876, 4092.9795351023054, 4130.401091841666, 4186.28833098374, 4192.685548583869, 8984.0, 9006.909930900607, 9015.774605634911, 9030.548472576345, 9039.09609039104, 4556.88607784808, 4563.201439748031, 4627.814887404466, 4642.9535704643195, 4648.814047438064, 4248.87253382399, 4253.149370125987, 4254.957450425518, 4255.319148936155, 4257.361395790656, 3572.499850020999, 3590.174259920233, 3597.6402359763815, 3613.6928361089076, 3625.9637403625625, 1105.4528008635777, 1158.8319693644544, 1165.2251252916715, 1173.636172786439, 1176.923499972499, 1277.201748906933, 1297.9026573007063, 1302.3227921480777, 1318.6637407461037, 1331.9134256273505, 7269.127704675473, 7332.816679582947, 7347.08161479815, 7364.521306115085, 7375.004374409494, 434.42004923426947, 458.8322718680965, 466.8202741944308, 473.8673171511982, 475.05701183151416, 5698.430156984351, 5703.914441283384, 5784.421557844182, 5786.855328616734, 5824.242848429762, 9167.495787731605, 9416.858747118798, 9440.95279523598, 9457.574409151604, 9459.90540094604, 9036.8644470333, 9229.630814767468, 9256.352055356061, 9333.206677432494, 9344.065593440737, 6915.135608048994, 6932.896006559906, 6963.268856770169, 6983.0, 7063.1524217094275, 6913.723451061901, 6943.6875340608785, 6961.338672826073, 6978.68595913175, 6994.755183568551, 8047.356211503183, 8100.675972961016, 8196.221358970888, 8289.668413263402, 8341.624626891926, 8204.876926845982, 8270.669173233004, 8392.790180245544, 8394.076651568445, 8421.536815475207, 6370.108184854032, 6401.327860574573, 6401.64790936503, 6407.423331900151, 6417.935820641734, 7074.29257074309, 7148.785536433916, 7205.71177152929, 7212.459065570209, 7214.855702886018, 6954.547954382949, 6978.651067446506, 6996.720131194696, 7010.263922288086, 7047.119110111236, 6732.6297053660555, 6776.051352810512, 6815.284395138439, 6842.486813489108, 6955.686994085379, 6615.338466153346, 6672.165979252593, 6674.766383176566, 6676.365745254193, 6682.63245521501, 6288.0, 6375.553711240169, 6385.616862988236, 6388.0, 6399.616023038632, 5895.587308888508, 5896.321922978912, 6439.8068057958335, 6440.033994900802, 6463.3213512580505], "unit": "ms", "name": "mootools.html", "value": 4267.330477623481}, {"lowerIsBetter": false, "replicates": [308.27555245173835, 308.65430717596297, 313.4827534567963, 313.8650380336454, 315.7205872802569, 5991.161237426763, 6052.092186172076, 6056.576039677225, 6082.148499210114, 6090.878182436349, 5999.850003749909, 6002.339742628307, 6005.0, 6007.068904319837, 6008.369121242272, 4400.82396704133, 4505.301678239878, 4594.127115847995, 4596.5403459653935, 4597.218472859605, 4497.0, 4602.102589994953, 4623.352730617717, 4625.468071171807, 4635.791389387468, 4552.021315417184, 4561.908761824761, 4567.38340324057, 4569.543045695421, 4574.268117101265, 4386.539413361599, 4442.489113751928, 4453.398791163184, 4479.46246450428, 4562.270036794113, 3944.526656801202, 3974.364101743707, 3985.163115745697, 3988.3020471417385, 3989.5212574490956, 5704.657720536739, 5711.05767548354, 5724.141378793214, 5742.0238559445, 5760.596758226926, 5273.288106105665, 5284.048871203182, 5288.947110528884, 5289.973550132263, 5329.440408757102, 6237.376262373772, 6396.808095757135, 6400.295967443624, 6401.743930242785, 6413.198350206224, 4270.829166833323, 4272.33778764292, 4273.786310684484, 4273.807678654468, 4279.079997800457, 6577.769778057745, 6620.23867255267, 6645.734170633217, 6646.0, 6647.401733843977, 4272.166927449118, 4366.19225443294, 4371.978140109279, 4373.322135069069, 4389.253826849444, 4421.425214722066, 4425.690201685916, 4427.955720442755, 4429.025614364835, 4430.62339701129, 5207.895842083137, 5213.635045546851, 5214.921776173361, 5222.791088356499, 5226.503482169188, 6435.0, 6441.452476539453, 6448.3229260927865, 6448.355164483514, 6448.903266451007, 1546.1341648676553, 1550.9457168999031, 1566.0, 1581.5097319830888, 1584.7305957987169, 1014.0873214107215, 1021.1116328793848, 1027.0961553832728, 1041.5209003858158, 1042.1610603464133, 6239.563230573816, 6241.563090583615, 6242.500599951993, 6242.531810114168, 6249.281332646713, 6619.205695316593, 6623.933760662428, 6625.503087268474, 6626.105475760807, 6627.10534077903, 6087.026075827846, 6203.844903877349, 6223.657698826518, 6225.221847269091, 6232.501399887998, 3587.0494319005484, 3599.3881040223223, 3605.5132557104457, 3605.6935160511125, 3616.8914932552066, 6809.693563789641, 6812.6934287957165, 6814.807408703457, 6820.727170913207, 6827.1807383113355, 6824.863502730017, 6827.317268273133, 6828.760993365208, 6829.658517074126, 6833.829154271183, 6669.266380698119, 6905.1713794345, 6912.999999999899, 6919.411849992706, 6943.270956549591, 6712.328767123248, 6721.22705888829, 6722.899156512558, 6728.562643428161, 6735.259121496533, 6436.645984470899, 6686.598804071771, 6687.1975362956755, 6687.933120668731, 6697.497687673346, 6863.416609588241, 6865.41643960269, 6875.724971001104, 6877.3810357068105, 6877.690503927235, 4444.688871778944, 4479.955200448084, 4482.618977386958, 4485.147821913826, 4486.79809408584, 6676.766313179015, 6685.298043705341, 6690.799276021728, 6691.1636045494315, 6695.732170713117, 6362.109304697438, 6484.448821850006, 6486.156799616019, 6495.090687303876, 6500.934990650033, 6453.1610890583925, 6460.935390646221, 6461.547691661539, 6466.870662586816, 6485.54601177913, 6196.566240363311, 6378.043293505825, 6379.872402552016, 6383.329750376143, 6383.521235907233, 6277.2153480814895, 6386.61680299165, 6392.552521323462, 6430.228372595318, 6512.08830763684, 6257.937420625735, 6275.717592708248, 6287.46556542699, 6333.303336632875, 6338.841528961812], "unit": "ms", "name": "prototype.html", "value": 4793.455715037058}, {"lowerIsBetter": false, "replicates": [741.3290971670638, 824.4435006370705, 835.4861760017587, 836.3727204596553, 852.9445586036909, 12635.494580216791, 12849.100562960597, 12860.228386296829, 12888.548900788473, 12911.870881291195, 12030.157888947777, 12136.514539418435, 12136.817947730791, 12143.878561214386, 12157.939210303959, 667.5260564998856, 685.3934268172653, 790.3598085550693, 809.5709274084729, 825.3644693585935, 820.5158956215831, 820.7906983719143, 827.6730691376903, 827.8096037911284, 828.9751307460785, 847.7414388611471, 856.618804631939, 856.8414843253987, 857.7212405968086, 858.3605214115493, 689.4243306838796, 707.2079271216245, 820.122468958218, 846.0017179727919, 849.4945507423073, 626.9874602507924, 627.6830200748593, 627.6987046217795, 628.8427893026743, 630.0234636313695, 12383.133180677267, 12534.937325313405, 12560.748785024249, 12574.182678125984, 12579.559715409916, 12414.0, 12962.740745185043, 13014.479420823156, 13144.474221031147, 13336.466541338335, 9797.706068817946, 9831.213502919822, 10181.083702466814, 10227.130693891027, 10329.07038366543, 3513.7364697647777, 3516.261585067139, 3523.9295214095573, 3534.7480318107405, 3603.4774957631257, 44412.3338149928, 45222.0, 45266.0, 45291.320630190574, 45344.77327613374, 9900.257480688977, 10113.342632728849, 10172.69481915529, 10529.947350263199, 10621.73445663849, 18066.638667226583, 18090.728639070425, 18101.0, 18102.0, 18105.7284140738, 7325.816854578572, 7361.521501102411, 7367.742129025459, 7370.594617295993, 7582.62086895653, 4344.218040752695, 4452.109578084396, 4509.481409637868, 4596.724196548218, 4634.0, 3026.2585666511404, 3093.334932989418, 3099.178717639782, 3100.860461279248, 3103.1311232854837, 526.8050821196182, 528.0995901987103, 547.7726743401523, 548.9478499542616, 552.3951273355669, 655.2431941108027, 656.037533298879, 681.9011243369683, 699.087994335492, 701.235653138082, 8696.695615653422, 8815.074417186102, 8826.161514656098, 8856.822863542693, 8981.281497480186, 613.1691557938929, 629.7732816186169, 635.378343442371, 646.1051443750346, 667.2126890269528, 3899.6100389960775, 3912.158885839558, 3982.880513584539, 4114.732542384795, 4333.826646934087, 51512.48487515152, 53000.46999530032, 53290.0, 53532.73233633807, 54252.45747542553, 46916.0, 47902.760486197345, 48509.0, 48727.269090963666, 49002.75498622484, 46894.0, 47041.0, 47244.29133562999, 47552.762236189286, 48044.0, 9060.184583387527, 9073.591688373906, 9087.454752714859, 9171.220446262143, 9233.215176710055, 34866.0, 35414.29171416609, 35713.28573428569, 35925.0, 36155.63844361523, 44751.55248447474, 45028.32457513206, 46421.0, 48370.274445884046, 48405.51594484151, 11498.0, 11637.301761894314, 11673.533058677558, 11804.704882377666, 11822.34977076269, 12282.280480732526, 12296.508139674313, 12563.93718031404, 12649.914050933472, 13400.0, 11882.881171188177, 12046.879531204575, 12174.39128043612, 12535.24788512692, 12536.74926501483, 11643.476043578228, 11783.882161178279, 11933.880661193278, 11994.700132496757, 12300.0, 11745.29528228309, 12013.10665591036, 12132.514699412277, 12173.939130304292, 12600.306983116017, 10171.745706357104, 10353.896461035594, 10395.662455105601, 10434.843477347998, 10519.78960420772, 9413.70850150056, 9450.621975121194, 9832.213422926006, 10128.696139115838, 10141.0], "unit": "ms", "name": "yui.html", "value": 6324.859881646941}], "name": "dromaeo_css", "value": 6816.19609337863}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 108.0, 102.0, 109.0, 109.0, 110.0, 99.0, 111.0, 108.0, 100.0], "unit": "ms", "name": "ai-astar", "value": 106.4}, {"lowerIsBetter": true, "replicates": [151.0, 131.0, 158.0, 164.0, 125.0, 154.0, 155.0, 125.0, 174.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.4}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 159.0, 159.0, 159.0, 159.0, 160.0, 161.0, 162.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 85.0, 91.0, 71.0, 71.0, 72.0, 104.0, 91.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 137.0, 92.0, 93.0, 93.0, 137.0, 93.0, 93.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [60.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 62.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 60.5}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 69.0, 70.0, 70.0, 70.0, 70.0, 68.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [82.0, 83.0, 85.0, 83.0, 91.0, 82.0, 83.0, 84.0, 93.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.1}, {"lowerIsBetter": true, "replicates": [124.0, 123.0, 119.0, 114.0, 121.0, 125.0, 129.0, 119.0, 128.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.7}, {"lowerIsBetter": true, "replicates": [150.0, 162.0, 151.0, 148.0, 151.0, 158.0, 150.0, 151.0, 149.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 151.4}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 69.0, 67.0, 68.0, 66.0, 66.0, 67.0, 68.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.3}], "name": "kraken", "value": 1504.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [184.46781036709095], "unit": "score", "name": "Richards", "value": 191.37214199999997}, {"lowerIsBetter": false, "replicates": [194.5903872348706], "unit": "score", "name": "DeltaBlue", "value": 339.780402}, {"lowerIsBetter": false, "replicates": [283.7684449489217], "unit": "score", "name": "Encrypt", "value": 938.0218440000002}, {"lowerIsBetter": false, "replicates": [4995.024875621891], "unit": "score", "name": "Decrypt", "value": 53.289224103585696}, {"lowerIsBetter": false, "replicates": [1962.7450980392157], "unit": "score", "name": "RayTrace", "value": 377.0173726273725}, {"lowerIsBetter": false, "replicates": [767.4597083653108], "unit": "score", "name": "Earley", "value": 868.401289}, {"lowerIsBetter": false, "replicates": [12292.682926829268], "unit": "score", "name": "Boyer", "value": 54.21623611111113}, {"lowerIsBetter": false, "replicates": [25500.0], "unit": "score", "name": "RegExp", "value": 35.72490196078431}, {"lowerIsBetter": false, "replicates": [560.5381165919282], "unit": "score", "name": "Splay", "value": 145.37994400000002}, {"lowerIsBetter": false, "replicates": [4864.077669902913], "unit": "score", "name": "NavierStokes", "value": 305.0938123752493}], "name": "v8_7", "value": 19848.496698286148}]} 10:11:49 INFO - RETURN: dromaeo_css: 6816.20 10:11:49 INFO - RETURN: kraken: 1504.30 10:11:49 INFO - RETURN: v8_7: 19848.50 10:11:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19848.50"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1504.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6816.20"}}} 10:11:49 INFO - Return code: 0 10:11:49 INFO - # TBPL SUCCESS # 10:11:49 INFO - Running post-action listener: _resource_record_post_action 10:11:49 INFO - Running post-run listener: _resource_record_post_run 10:11:50 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 0; Write bytes: 497332224; Read time: 0; Write time: 456324 10:11:50 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 117784576; Read time: 0; Write time: 52312 10:11:50 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 0; Write bytes: 313794560; Read time: 0; Write time: 298700 10:11:50 INFO - Running post-run listener: _upload_blobber_files 10:11:50 INFO - Blob upload gear active. 10:11:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:11:50 INFO - Copying logs to upload dir... 10:11:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1268.994327 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-11-23 10:11:50.387328) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 10:11:50.391719) ========= 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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010010 build_url: 'https://queue.taskcluster.net/v1/task/qsWPhsHnRuazZXhAs6utwQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.06 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 10:11:50.457247) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 10:11:50.457696) ========= 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=a9e534cca236dfa67d42a089000001c1-1448299584.4850-1479628177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 10:11:50.512589) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 10:11:50.513189) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 10:11:50.513731) ========= ========= Total master_lag: 1.11 =========