builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1448332180.23 results: success (0) buildid: 20151123173007 builduid: b3fea3e2d39f45999cb24e360a3e0d78 revision: c9131227ffdeee42cb1a3b4209b704e94c7bc177 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.231856) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.232430) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.232775) ========= 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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006473 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.261634) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.262115) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.275865) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.276318) ========= 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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004611 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.327151) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.327468) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.327854) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.328203) ========= 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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 18:29:41-- 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% 34.2M=0s 2015-11-23 18:29:41 (34.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.247159 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.596780) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.597178) ========= 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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013348 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.648309) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:40.648700) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev c9131227ffdeee42cb1a3b4209b704e94c7bc177 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev c9131227ffdeee42cb1a3b4209b704e94c7bc177 --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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 18:29:41,732 truncating revision to first 12 chars 2015-11-23 18:29:41,732 Setting DEBUG logging. 2015-11-23 18:29:41,732 attempt 1/10 2015-11-23 18:29:41,732 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/c9131227ffde?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 18:29:41,954 unpacking tar archive at: b2g-inbound-c9131227ffde/testing/mozharness/ program finished with exit code 0 elapsedTime=0.508235 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:41.171341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:41.171683) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:41.181716) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:41.182169) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:41.182673) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-23 18:29:41.182995) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:42 INFO - MultiFileLogger online at 20151123 18:29:42 in /builds/slave/test-pgo 18:29:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 18:29:42 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:29:42 INFO - {'append_to_log': False, 18:29:42 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:29:42 INFO - 'blob_upload_branch': 'B2G-Inbound', 18:29:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:29:42 INFO - 'branch': 'B2G-Inbound', 18:29:42 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:42 INFO - 'config_files': ('talos/linux_config.py',), 18:29:42 INFO - 'default_actions': ('clobber', 18:29:42 INFO - 'read-buildbot-config', 18:29:42 INFO - 'download-and-extract', 18:29:42 INFO - 'populate-webroot', 18:29:42 INFO - 'create-virtualenv', 18:29:42 INFO - 'install', 18:29:42 INFO - 'run-tests'), 18:29:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:42 INFO - 'download_minidump_stackwalk': True, 18:29:42 INFO - 'download_symbols': 'ondemand', 18:29:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:42 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:42 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:42 INFO - 'installer_path': 'installer.exe', 18:29:42 INFO - 'log_level': 'info', 18:29:42 INFO - 'log_name': 'talos', 18:29:42 INFO - 'log_to_console': True, 18:29:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:42 INFO - 'opt_config_files': (), 18:29:42 INFO - 'pip_index': False, 18:29:42 INFO - 'sps_profile': False, 18:29:42 INFO - 'sps_profile_interval': 0, 18:29:42 INFO - 'suite': 'dromaeojs', 18:29:42 INFO - 'system_bits': '64', 18:29:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:42 INFO - 'title': 'talos-linux64-ix-066', 18:29:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:42 INFO - 'use_talos_json': True, 18:29:42 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:29:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:42 INFO - 'work_dir': 'build'} 18:29:42 INFO - ##### 18:29:42 INFO - ##### Running clobber step. 18:29:42 INFO - ##### 18:29:42 INFO - Running pre-action listener: _resource_record_pre_action 18:29:42 INFO - Running main action method: clobber 18:29:42 INFO - rmtree: /builds/slave/test-pgo/build 18:29:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:29:43 INFO - Running post-action listener: _resource_record_post_action 18:29:43 INFO - ##### 18:29:43 INFO - ##### Running read-buildbot-config step. 18:29:43 INFO - ##### 18:29:43 INFO - Running pre-action listener: _resource_record_pre_action 18:29:43 INFO - Running main action method: read_buildbot_config 18:29:43 INFO - Using buildbot properties: 18:29:43 INFO - { 18:29:43 INFO - "properties": { 18:29:43 INFO - "buildnumber": 52, 18:29:43 INFO - "product": "firefox", 18:29:43 INFO - "script_repo_revision": "production", 18:29:43 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 18:29:43 INFO - "repository": "", 18:29:43 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 18:29:43 INFO - "buildid": "20151123173007", 18:29:43 INFO - "slavename": "talos-linux64-ix-066", 18:29:43 INFO - "pgo_build": "True", 18:29:43 INFO - "basedir": "/builds/slave/test-pgo", 18:29:43 INFO - "project": "", 18:29:43 INFO - "platform": "ubuntu64_hw", 18:29:43 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:29:43 INFO - "slavebuilddir": "test", 18:29:43 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 18:29:43 INFO - "branch": "b2g-inbound", 18:29:43 INFO - "repo_path": "integration/b2g-inbound", 18:29:43 INFO - "stage_platform": "linux64-pgo", 18:29:43 INFO - "builduid": "b3fea3e2d39f45999cb24e360a3e0d78", 18:29:43 INFO - "revision": "c9131227ffdeee42cb1a3b4209b704e94c7bc177" 18:29:43 INFO - }, 18:29:43 INFO - "sourcestamp": { 18:29:43 INFO - "repository": "", 18:29:43 INFO - "hasPatch": false, 18:29:43 INFO - "project": "", 18:29:43 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 18:29:43 INFO - "changes": [ 18:29:43 INFO - { 18:29:43 INFO - "category": null, 18:29:43 INFO - "files": [ 18:29:43 INFO - { 18:29:43 INFO - "url": null, 18:29:43 INFO - "name": "https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:29:43 INFO - } 18:29:43 INFO - ], 18:29:43 INFO - "repository": "", 18:29:43 INFO - "rev": "c9131227ffdeee42cb1a3b4209b704e94c7bc177", 18:29:43 INFO - "who": "sendchange", 18:29:43 INFO - "when": 1448332148, 18:29:43 INFO - "number": 6698509, 18:29:43 INFO - "comments": "", 18:29:43 INFO - "project": "", 18:29:43 INFO - "at": "Mon 23 Nov 2015 18:29:08", 18:29:43 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 18:29:43 INFO - "revlink": "", 18:29:43 INFO - "properties": [ 18:29:43 INFO - [ 18:29:43 INFO - "buildid", 18:29:43 INFO - "20151123173007", 18:29:43 INFO - "Change" 18:29:43 INFO - ], 18:29:43 INFO - [ 18:29:43 INFO - "builduid", 18:29:43 INFO - "b3fea3e2d39f45999cb24e360a3e0d78", 18:29:43 INFO - "Change" 18:29:43 INFO - ], 18:29:43 INFO - [ 18:29:43 INFO - "pgo_build", 18:29:43 INFO - "True", 18:29:43 INFO - "Change" 18:29:43 INFO - ] 18:29:43 INFO - ], 18:29:43 INFO - "revision": "c9131227ffdeee42cb1a3b4209b704e94c7bc177" 18:29:43 INFO - } 18:29:43 INFO - ], 18:29:43 INFO - "revision": "c9131227ffdeee42cb1a3b4209b704e94c7bc177" 18:29:43 INFO - } 18:29:43 INFO - } 18:29:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:29:43 INFO - Running post-action listener: _resource_record_post_action 18:29:43 INFO - ##### 18:29:43 INFO - ##### Running download-and-extract step. 18:29:43 INFO - ##### 18:29:43 INFO - Running pre-action listener: _resource_record_pre_action 18:29:43 INFO - Running main action method: download_and_extract 18:29:43 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:43 INFO - https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:29:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/test_packages.json 18:29:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/test_packages.json 18:29:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:29:44 INFO - Downloaded 1302 bytes. 18:29:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:29:44 INFO - Using the following test package requirements: 18:29:44 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:29:44 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'jsshell-linux-x86_64.zip'], 18:29:44 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:29:44 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:29:44 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:29:44 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:29:44 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:29:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:44 INFO - https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:46 INFO - Downloaded 22526653 bytes. 18:29:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:29:47 INFO - Return code: 0 18:29:47 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:47 INFO - https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:48 INFO - Downloaded 22526653 bytes. 18:29:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:29:50 INFO - Return code: 0 18:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:50 INFO - https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:29:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:29:51 INFO - Downloaded 11130997 bytes. 18:29:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:29:51 INFO - Return code: 0 18:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:51 INFO - https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:29:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:29:53 INFO - Downloaded 57554745 bytes. 18:29:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:53 INFO - mkdir: /builds/slave/test-pgo/properties 18:29:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:29:53 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:29:53 INFO - Contents: 18:29:53 INFO - build_url:https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:29:53 INFO - Running post-action listener: _resource_record_post_action 18:29:53 INFO - Running post-action listener: set_extra_try_arguments 18:29:53 INFO - ##### 18:29:53 INFO - ##### Running populate-webroot step. 18:29:53 INFO - ##### 18:29:53 INFO - Running pre-action listener: _resource_record_pre_action 18:29:53 INFO - Running main action method: populate_webroot 18:29:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:29:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:29:53 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:29:53 INFO - '../fennec_ids.txt'], 18:29:53 INFO - 'tests': ['tcheck2']}, 18:29:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:29:53 INFO - '--tppagecycles', 18:29:53 INFO - '7'], 18:29:53 INFO - 'tests': ['tsvgm']}}, 18:29:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:29:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:29:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:29:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:29:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'tests': ['damp', 'tps']}, 18:29:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['damp', 'tps']}, 18:29:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:29:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:53 INFO - 'other': {'tests': ['a11yr', 18:29:53 INFO - 'ts_paint', 18:29:53 INFO - 'tpaint', 18:29:53 INFO - 'sessionrestore', 18:29:53 INFO - 'sessionrestore_no_auto_restore']}, 18:29:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['a11yr', 18:29:53 INFO - 'ts_paint', 18:29:53 INFO - 'tpaint', 18:29:53 INFO - 'sessionrestore', 18:29:53 INFO - 'sessionrestore_no_auto_restore']}, 18:29:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['a11yr', 18:29:53 INFO - 'ts_paint', 18:29:53 INFO - 'tpaint', 18:29:53 INFO - 'sessionrestore', 18:29:53 INFO - 'sessionrestore_no_auto_restore']}, 18:29:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['a11yr', 18:29:53 INFO - 'ts_paint', 18:29:53 INFO - 'tpaint', 18:29:53 INFO - 'sessionrestore', 18:29:53 INFO - 'sessionrestore_no_auto_restore']}, 18:29:53 INFO - 'other_l64': {'tests': ['a11yr', 18:29:53 INFO - 'ts_paint', 18:29:53 INFO - 'tpaint', 18:29:53 INFO - 'sessionrestore', 18:29:53 INFO - 'sessionrestore_no_auto_restore']}, 18:29:53 INFO - 'other_nol64': {'tests': ['a11yr', 18:29:53 INFO - 'ts_paint', 18:29:53 INFO - 'tpaint', 18:29:53 INFO - 'sessionrestore', 18:29:53 INFO - 'sessionrestore_no_auto_restore']}, 18:29:53 INFO - 'svgr': {'tests': ['tsvgx', 18:29:53 INFO - 'tsvgr_opacity', 18:29:53 INFO - 'tart', 18:29:53 INFO - 'tscrollx', 18:29:53 INFO - 'cart']}, 18:29:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['tsvgx', 18:29:53 INFO - 'tsvgr_opacity', 18:29:53 INFO - 'tart', 18:29:53 INFO - 'tscrollx', 18:29:53 INFO - 'cart']}, 18:29:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'tests': ['tp5o']}, 18:29:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'talos_options': ['--e10s'], 18:29:53 INFO - 'tests': ['tp5o']}, 18:29:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'talos_options': ['--xperf_path', 18:29:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:53 INFO - 'tests': ['tp5n']}, 18:29:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:53 INFO - 'talos_options': ['--e10s', 18:29:53 INFO - '--xperf_path', 18:29:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:53 INFO - 'tests': ['tp5n']}}, 18:29:53 INFO - 'talos.zip': {'path': '', 18:29:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:29:53 INFO - Running post-action listener: _resource_record_post_action 18:29:53 INFO - ##### 18:29:53 INFO - ##### Running create-virtualenv step. 18:29:53 INFO - ##### 18:29:53 INFO - Running pre-action listener: _resource_record_pre_action 18:29:53 INFO - Running main action method: create_virtualenv 18:29:53 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:29:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:29:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:29:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:54 INFO - Using real prefix '/usr' 18:29:54 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:29:54 INFO - Installing distribute.............................................................................................................................................................................................done. 18:29:56 INFO - Installing pip.................done. 18:29:56 INFO - Return code: 0 18:29:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:29:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:29:56 INFO - Using env: {'DISPLAY': ':0', 18:29:56 INFO - 'HOME': '/home/cltbld', 18:29:56 INFO - 'LANG': 'en_US.UTF-8', 18:29:56 INFO - 'LANGUAGE': 'en_US:en', 18:29:56 INFO - 'LOGNAME': 'cltbld', 18:29:56 INFO - 'MAIL': '/var/mail/cltbld', 18:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:56 INFO - 'MOZ_NO_REMOTE': '1', 18:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:56 INFO - 'NO_EM_RESTART': '1', 18:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:29:56 INFO - 'PWD': '/builds/slave/test-pgo', 18:29:56 INFO - 'SHELL': '/bin/bash', 18:29:56 INFO - 'SHLVL': '1', 18:29:56 INFO - 'TERM': 'linux', 18:29:56 INFO - 'TMOUT': '86400', 18:29:56 INFO - 'USER': 'cltbld', 18:29:56 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:56 INFO - '_': '/tools/buildbot/bin/python'} 18:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:29:56 INFO - Cleaning up... 18:29:56 INFO - Return code: 0 18:29:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:29:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:29:56 INFO - Using env: {'DISPLAY': ':0', 18:29:56 INFO - 'HOME': '/home/cltbld', 18:29:56 INFO - 'LANG': 'en_US.UTF-8', 18:29:56 INFO - 'LANGUAGE': 'en_US:en', 18:29:56 INFO - 'LOGNAME': 'cltbld', 18:29:56 INFO - 'MAIL': '/var/mail/cltbld', 18:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:56 INFO - 'MOZ_NO_REMOTE': '1', 18:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:56 INFO - 'NO_EM_RESTART': '1', 18:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:29:56 INFO - 'PWD': '/builds/slave/test-pgo', 18:29:56 INFO - 'SHELL': '/bin/bash', 18:29:56 INFO - 'SHLVL': '1', 18:29:56 INFO - 'TERM': 'linux', 18:29:56 INFO - 'TMOUT': '86400', 18:29:56 INFO - 'USER': 'cltbld', 18:29:56 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:56 INFO - '_': '/tools/buildbot/bin/python'} 18:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:56 INFO - Downloading/unpacking psutil>=0.7.1 18:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:58 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:29:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:29:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:29:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:58 INFO - Installing collected packages: psutil 18:29:58 INFO - Running setup.py install for psutil 18:29:58 INFO - building 'psutil._psutil_linux' extension 18:29:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:29:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:29:58 INFO - building 'psutil._psutil_posix' extension 18:29:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:29:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:29:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:59 INFO - Successfully installed psutil 18:29:59 INFO - Cleaning up... 18:29:59 INFO - Return code: 0 18:29:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:59 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 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:29:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:29:59 INFO - Using env: {'DISPLAY': ':0', 18:29:59 INFO - 'HOME': '/home/cltbld', 18:29:59 INFO - 'LANG': 'en_US.UTF-8', 18:29:59 INFO - 'LANGUAGE': 'en_US:en', 18:29:59 INFO - 'LOGNAME': 'cltbld', 18:29:59 INFO - 'MAIL': '/var/mail/cltbld', 18:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:59 INFO - 'MOZ_NO_REMOTE': '1', 18:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:59 INFO - 'NO_EM_RESTART': '1', 18:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:29:59 INFO - 'PWD': '/builds/slave/test-pgo', 18:29:59 INFO - 'SHELL': '/bin/bash', 18:29:59 INFO - 'SHLVL': '1', 18:29:59 INFO - 'TERM': 'linux', 18:29:59 INFO - 'TMOUT': '86400', 18:29:59 INFO - 'USER': 'cltbld', 18:29:59 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:59 INFO - '_': '/tools/buildbot/bin/python'} 18:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:30:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:30:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:30:01 INFO - Installing collected packages: mozsystemmonitor 18:30:01 INFO - Running setup.py install for mozsystemmonitor 18:30:01 INFO - Successfully installed mozsystemmonitor 18:30:01 INFO - Cleaning up... 18:30:01 INFO - Return code: 0 18:30:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:01 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 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:30:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:01 INFO - Using env: {'DISPLAY': ':0', 18:30:01 INFO - 'HOME': '/home/cltbld', 18:30:01 INFO - 'LANG': 'en_US.UTF-8', 18:30:01 INFO - 'LANGUAGE': 'en_US:en', 18:30:01 INFO - 'LOGNAME': 'cltbld', 18:30:01 INFO - 'MAIL': '/var/mail/cltbld', 18:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:01 INFO - 'MOZ_NO_REMOTE': '1', 18:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:01 INFO - 'NO_EM_RESTART': '1', 18:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:01 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:01 INFO - 'SHELL': '/bin/bash', 18:30:01 INFO - 'SHLVL': '1', 18:30:01 INFO - 'TERM': 'linux', 18:30:01 INFO - 'TMOUT': '86400', 18:30:01 INFO - 'USER': 'cltbld', 18:30:01 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:01 INFO - '_': '/tools/buildbot/bin/python'} 18:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:30:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:30:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:30:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - Downloading docopt-0.6.1.tar.gz 18:30:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:30:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:04 INFO - Running setup.py install for blobuploader 18:30:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:04 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:30:04 INFO - Running setup.py install for requests 18:30:04 INFO - Running setup.py install for docopt 18:30:04 INFO - Successfully installed blobuploader requests docopt 18:30:04 INFO - Cleaning up... 18:30:04 INFO - Return code: 0 18:30:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:04 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 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:30:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:04 INFO - Using env: {'DISPLAY': ':0', 18:30:04 INFO - 'HOME': '/home/cltbld', 18:30:04 INFO - 'LANG': 'en_US.UTF-8', 18:30:04 INFO - 'LANGUAGE': 'en_US:en', 18:30:04 INFO - 'LOGNAME': 'cltbld', 18:30:04 INFO - 'MAIL': '/var/mail/cltbld', 18:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:04 INFO - 'MOZ_NO_REMOTE': '1', 18:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:04 INFO - 'NO_EM_RESTART': '1', 18:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:04 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:04 INFO - 'SHELL': '/bin/bash', 18:30:04 INFO - 'SHLVL': '1', 18:30:04 INFO - 'TERM': 'linux', 18:30:04 INFO - 'TMOUT': '86400', 18:30:04 INFO - 'USER': 'cltbld', 18:30:04 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:04 INFO - '_': '/tools/buildbot/bin/python'} 18:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:04 INFO - Running setup.py (path:/tmp/pip-4w4jq8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:04 INFO - Running setup.py (path:/tmp/pip-4tG3CO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:04 INFO - Running setup.py (path:/tmp/pip-qf3zuT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:05 INFO - Running setup.py (path:/tmp/pip-MraXdf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:05 INFO - Running setup.py (path:/tmp/pip-cqS3aV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:05 INFO - Running setup.py (path:/tmp/pip-RkFiWu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:05 INFO - Running setup.py (path:/tmp/pip-s6jjml-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:05 INFO - Running setup.py (path:/tmp/pip-IXZHX1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:05 INFO - Running setup.py (path:/tmp/pip-Fj_BvP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:05 INFO - Running setup.py (path:/tmp/pip-0dqb8z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:05 INFO - Running setup.py (path:/tmp/pip-Bz36PX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:05 INFO - Running setup.py (path:/tmp/pip-p8siZF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:05 INFO - Running setup.py (path:/tmp/pip-7e8mUq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:05 INFO - Running setup.py (path:/tmp/pip-iuk0Ce-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:05 INFO - Running setup.py (path:/tmp/pip-ZClG3X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:05 INFO - Running setup.py (path:/tmp/pip-hOij7b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:05 INFO - Running setup.py (path:/tmp/pip-jqXSu8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:06 INFO - Running setup.py install for manifestparser 18:30:06 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozcrash 18:30:06 INFO - Running setup.py install for mozdebug 18:30:06 INFO - Running setup.py install for mozdevice 18:30:06 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozfile 18:30:06 INFO - Running setup.py install for mozhttpd 18:30:06 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozinfo 18:30:06 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozInstall 18:30:06 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:30:06 INFO - Running setup.py install for mozleak 18:30:06 INFO - Running setup.py install for mozlog 18:30:07 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Running setup.py install for moznetwork 18:30:07 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Running setup.py install for mozprocess 18:30:07 INFO - Running setup.py install for mozprofile 18:30:07 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Running setup.py install for mozrunner 18:30:07 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Running setup.py install for mozscreenshot 18:30:07 INFO - Running setup.py install for moztest 18:30:07 INFO - Running setup.py install for mozversion 18:30:07 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:30:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:07 INFO - Cleaning up... 18:30:07 INFO - Return code: 0 18:30:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:30:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:07 INFO - Using env: {'DISPLAY': ':0', 18:30:07 INFO - 'HOME': '/home/cltbld', 18:30:07 INFO - 'LANG': 'en_US.UTF-8', 18:30:07 INFO - 'LANGUAGE': 'en_US:en', 18:30:07 INFO - 'LOGNAME': 'cltbld', 18:30:07 INFO - 'MAIL': '/var/mail/cltbld', 18:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:07 INFO - 'MOZ_NO_REMOTE': '1', 18:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:07 INFO - 'NO_EM_RESTART': '1', 18:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:07 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:07 INFO - 'SHELL': '/bin/bash', 18:30:07 INFO - 'SHLVL': '1', 18:30:07 INFO - 'TERM': 'linux', 18:30:07 INFO - 'TMOUT': '86400', 18:30:07 INFO - 'USER': 'cltbld', 18:30:07 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:07 INFO - '_': '/tools/buildbot/bin/python'} 18:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:08 INFO - Running setup.py (path:/tmp/pip-xaYrXq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:08 INFO - Running setup.py (path:/tmp/pip-vJTKss-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:08 INFO - Running setup.py (path:/tmp/pip-rcL5ej-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:08 INFO - Running setup.py (path:/tmp/pip-ajJdYf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:08 INFO - Running setup.py (path:/tmp/pip-JWZa_R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:08 INFO - Running setup.py (path:/tmp/pip-4Bx_Ox-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:08 INFO - Running setup.py (path:/tmp/pip-1UD2DO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:08 INFO - Running setup.py (path:/tmp/pip-RLJEWn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:08 INFO - Running setup.py (path:/tmp/pip-QLcyyu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:08 INFO - Running setup.py (path:/tmp/pip-c3_6Yq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:08 INFO - Running setup.py (path:/tmp/pip-FrZjUV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:08 INFO - Running setup.py (path:/tmp/pip-52_VFR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:08 INFO - Running setup.py (path:/tmp/pip-ziWBuT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:30:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:09 INFO - Running setup.py (path:/tmp/pip-iKG8_D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:09 INFO - Running setup.py (path:/tmp/pip-zm80nx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:09 INFO - Running setup.py (path:/tmp/pip-vr3BXd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:09 INFO - Running setup.py (path:/tmp/pip-OOg_ve-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:10 INFO - Downloading blessings-1.5.1.tar.gz 18:30:10 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:30:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:11 INFO - Installing collected packages: blessings 18:30:11 INFO - Running setup.py install for blessings 18:30:11 INFO - Successfully installed blessings 18:30:11 INFO - Cleaning up... 18:30:11 INFO - Return code: 0 18:30:11 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:30:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:30:11 INFO - Reading from file tmpfile_stdout 18:30:11 INFO - Current package versions: 18:30:11 INFO - argparse == 1.2.1 18:30:11 INFO - blessings == 1.5.1 18:30:11 INFO - blobuploader == 1.2.4 18:30:11 INFO - docopt == 0.6.1 18:30:11 INFO - manifestparser == 1.1 18:30:11 INFO - mozInstall == 1.12 18:30:11 INFO - mozcrash == 0.16 18:30:11 INFO - mozdebug == 0.1 18:30:11 INFO - mozdevice == 0.47 18:30:11 INFO - mozfile == 1.2 18:30:11 INFO - mozhttpd == 0.7 18:30:11 INFO - mozinfo == 0.9 18:30:11 INFO - mozleak == 0.1 18:30:11 INFO - mozlog == 3.1 18:30:11 INFO - moznetwork == 0.27 18:30:11 INFO - mozprocess == 0.22 18:30:11 INFO - mozprofile == 0.27 18:30:11 INFO - mozrunner == 6.11 18:30:11 INFO - mozscreenshot == 0.1 18:30:11 INFO - mozsystemmonitor == 0.0 18:30:11 INFO - moztest == 0.7 18:30:11 INFO - mozversion == 1.4 18:30:11 INFO - psutil == 3.1.1 18:30:11 INFO - requests == 1.2.3 18:30:11 INFO - wsgiref == 0.1.2 18:30:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30: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')]} 18:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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', '-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 0x7f374dd7cc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb76c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f786e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, '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': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:11 INFO - Using env: {'DISPLAY': ':0', 18:30:11 INFO - 'HOME': '/home/cltbld', 18:30:11 INFO - 'LANG': 'en_US.UTF-8', 18:30:11 INFO - 'LANGUAGE': 'en_US:en', 18:30:11 INFO - 'LOGNAME': 'cltbld', 18:30:11 INFO - 'MAIL': '/var/mail/cltbld', 18:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:11 INFO - 'MOZ_NO_REMOTE': '1', 18:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:11 INFO - 'NO_EM_RESTART': '1', 18:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:11 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:11 INFO - 'SHELL': '/bin/bash', 18:30:11 INFO - 'SHLVL': '1', 18:30:11 INFO - 'TERM': 'linux', 18:30:11 INFO - 'TMOUT': '86400', 18:30:11 INFO - 'USER': 'cltbld', 18:30:11 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:11 INFO - '_': '/tools/buildbot/bin/python'} 18:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:11 INFO - Cleaning up... 18:30:11 INFO - Return code: 0 18:30:11 INFO - Running post-action listener: _resource_record_post_action 18:30:11 INFO - Running post-action listener: _start_resource_monitoring 18:30:11 INFO - Starting resource monitoring. 18:30:11 INFO - ##### 18:30:11 INFO - ##### Running install step. 18:30:11 INFO - ##### 18:30:11 INFO - Running pre-action listener: _resource_record_pre_action 18:30:11 INFO - Running main action method: install 18:30:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:30:11 INFO - Reading from file tmpfile_stdout 18:30:11 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:30:11 INFO - Reading from file tmpfile_stdout 18:30:11 INFO - Output received: 18:30:11 INFO - Usage: mozinstall [options] installer 18:30:11 INFO - Options: 18:30:11 INFO - -h, --help show this help message and exit 18:30:11 INFO - -d DEST, --destination=DEST 18:30:11 INFO - Directory to install application into. [default: 18:30:11 INFO - "/builds/slave/test-pgo"] 18:30:11 INFO - --app=APP Application being installed. [default: firefox] 18:30:11 INFO - mkdir: /builds/slave/test-pgo/build/application 18:30:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:30:26 INFO - Reading from file tmpfile_stdout 18:30:26 INFO - Output received: 18:30:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:30:26 INFO - Running post-action listener: _resource_record_post_action 18:30:26 INFO - ##### 18:30:26 INFO - ##### Running run-tests step. 18:30:26 INFO - ##### 18:30:26 INFO - Running pre-action listener: _resource_record_pre_action 18:30:26 INFO - Running main action method: run_tests 18:30:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:30:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:30:26 INFO - Python 2.7.3 18:30:26 INFO - Return code: 0 18:30:26 INFO - grabbing minidump binary from tooltool 18:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:26 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 0x1faea90>, '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 0x2000f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20012e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:30:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:30:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:30:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:26 INFO - Return code: 0 18:30:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:30:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:30:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:30:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:30:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:30:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:30:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 18:30:26 INFO - Using env: {'DISPLAY': ':0', 18:30:26 INFO - 'HOME': '/home/cltbld', 18:30:26 INFO - 'LANG': 'en_US.UTF-8', 18:30:26 INFO - 'LANGUAGE': 'en_US:en', 18:30:26 INFO - 'LOGNAME': 'cltbld', 18:30:26 INFO - 'MAIL': '/var/mail/cltbld', 18:30:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:30:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:26 INFO - 'MOZ_NO_REMOTE': '1', 18:30:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:30:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:26 INFO - 'NO_EM_RESTART': '1', 18:30:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:26 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:30:26 INFO - 'SHELL': '/bin/bash', 18:30:26 INFO - 'SHLVL': '1', 18:30:26 INFO - 'TERM': 'linux', 18:30:26 INFO - 'TMOUT': '86400', 18:30:26 INFO - 'USER': 'cltbld', 18:30:26 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787', 18:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:26 INFO - '_': '/tools/buildbot/bin/python'} 18:30:26 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 18:30:26 INFO - mozversion INFO | application_buildid: 20151123173007 18:30:26 INFO - mozversion INFO | application_changeset: c9131227ffdeee42cb1a3b4209b704e94c7bc177 18:30:26 INFO - mozversion INFO | application_display_name: Nightly 18:30:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:26 INFO - mozversion INFO | application_name: Firefox 18:30:26 INFO - mozversion INFO | application_remotingname: firefox 18:30:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 18:30:26 INFO - mozversion INFO | application_vendor: Mozilla 18:30:26 INFO - mozversion INFO | application_version: 45.0a1 18:30:26 INFO - mozversion INFO | platform_buildid: 20151123173007 18:30:26 INFO - mozversion INFO | platform_changeset: c9131227ffdeee42cb1a3b4209b704e94c7bc177 18:30:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 18:30:26 INFO - mozversion INFO | platform_version: 45.0a1 18:30:26 INFO - 2015-11-23 18:30:26,683 DEBUG : using testdate: 1448332226 18:30:26 INFO - 2015-11-23 18:30:26,683 DEBUG : actual date: 1448332226 18:30:26 INFO - 2015-11-23 18:30:26,729 INFO : starting webserver on 'localhost:44409' 18:30:26 INFO - 2015-11-23 18:30:26,730 INFO : Starting test suite talos-linux64-ix-066 18:30:26 INFO - 2015-11-23 18:30:26,731 INFO : Starting test dromaeo_css 18:30:26 INFO - 2015-11-23 18:30:26,731 DEBUG : operating with platform_type : linux_ 18:30:26 INFO - 2015-11-23 18:30:26,731 INFO : Initialising browser for dromaeo_css test... 18:30:26 INFO - 2015-11-23 18:30:26,746 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0YLxz_/profile http://localhost:44409/getInfo.html 18:30:30 INFO - 2015-11-23 18:30:30,973 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:30:30 INFO - 2015-11-23 18:30:30,973 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:30:30 INFO - 2015-11-23 18:30:30,973 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:30:30 INFO - 2015-11-23 18:30:30,973 DEBUG : BROWSER_OUTPUT: __metrics 18:30:31 INFO - 2015-11-23 18:30:31,095 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:32 INFO - 2015-11-23 18:30:32,416 INFO : Browser initialized. 18:30:32 INFO - 2015-11-23 18:30:32,417 INFO : Running cycle 1/1 for dromaeo_css test... 18:30:32 INFO - 2015-11-23 18:30:32,417 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0YLxz_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:30:44 INFO - 2015-11-23 18:30:44,226 DEBUG : BROWSER_OUTPUT: RSS: Main: 140623872 18:30:44 INFO - 2015-11-23 18:30:44,226 DEBUG : BROWSER_OUTPUT: 18:31:35 INFO - 2015-11-23 18:31:35,628 DEBUG : BROWSER_OUTPUT: 1448332295627 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:33:44 INFO - 2015-11-23 18:33:44,945 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44409/tests/dromaeo/cssquery-ext.html) 18:33:46 INFO - 2015-11-23 18:33:46,030 DEBUG : BROWSER_OUTPUT: RSS: Main: 229183488 18:33:46 INFO - 2015-11-23 18:33:46,030 DEBUG : BROWSER_OUTPUT: 18:36:48 INFO - 2015-11-23 18:36:48,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/dromaeo/cssquery-ext.html (next: http://localhost:44409/tests/dromaeo/cssquery-jquery.html) 18:36:49 INFO - 2015-11-23 18:36:49,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 225214464 18:36:49 INFO - 2015-11-23 18:36:49,080 DEBUG : BROWSER_OUTPUT: 18:39:50 INFO - 2015-11-23 18:39:50,898 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44409/tests/dromaeo/cssquery-mootools.html) 18:39:51 INFO - 2015-11-23 18:39:51,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 219377664 18:39:51 INFO - 2015-11-23 18:39:51,471 DEBUG : BROWSER_OUTPUT: 18:42:53 INFO - 2015-11-23 18:42:53,366 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44409/tests/dromaeo/cssquery-prototype.html) 18:42:53 INFO - 2015-11-23 18:42:53,948 DEBUG : BROWSER_OUTPUT: RSS: Main: 201662464 18:42:53 INFO - 2015-11-23 18:42:53,948 DEBUG : BROWSER_OUTPUT: 18:45:56 INFO - 2015-11-23 18:45:56,266 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44409/tests/dromaeo/cssquery-yui.html) 18:45:56 INFO - 2015-11-23 18:45:56,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 206127104 18:45:56 INFO - 2015-11-23 18:45:56,852 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/dromaeo/cssquery-yui.html (next: http://localhost:44409/tests/dromaeo/cssquery-dojo.html) 18:48:59 INFO - 2015-11-23 18:48:59,856 DEBUG : BROWSER_OUTPUT: RSS: Main: 250449920 18:48:59 INFO - 2015-11-23 18:48:59,857 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,857 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:48:59 INFO - 2015-11-23 18:48:59,857 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:48:59 INFO - 2015-11-23 18:48:59,857 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:48:59 INFO - 2015-11-23 18:48:59,857 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:48:59 INFO - 2015-11-23 18:48:59,859 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3162.256869635635;3208.197950512372;3244.9940518439284;3248.496483045129;3280.6719328067197;5276.287701160342;5366.8389948301565;5414.160805075219;5453;5465.72671366432;5342.065138600739;5371.785128594861;5443.8094666686675;5479.315085614298;5487.972560137204;4954.628402869781;4977.178765503706;4978.352814134176;4980.800767969295;5051.621128415365;5023.271625614284;5119.795208191668;5133.922991155136;5142.408623008346;5174.974125129369;4830.975845120752;4945.258211268302;4968.354113965162;5056.988602279541;5066.797328106872;4909.901801963976;4919.7540122993705;4929.605631549467;4929.852104436873;4940.530649588283;4897.461279259279;4919.016196760662;4926.630502712275;4927;4931.358923339979;5144.871378215537;5160.071187186305;5207.765650545734;5236.9738151309;5237.528622423962;5812.157237200625;5821.068629019336;5822.359540450547;5828.854278643068;5868.325142608612;5655.5475561954945;5838.299404071496;5840.182374467578;5858.091995740624;5872.853178670567;5368.194770784375;5452.318460192476;5473.0969390050595;5479.506844383985;5519.420460851593;5915.911261331084;5948.137520059568;6009;6015.849603759941;6023.45788879003;5646.774129034875;5776.306843178845;5785.479306862402;5823.126531020381;5823.2429784128635;5792.218050563204;5911.852203694942;5912.054071348563;5914.201582786269;5967.940320596738;5691.715414229272;5801.796937107181;5807;5808.215890854765;5813.912791308134;5610;5716.228309178291;5721.370649228582;5738.799142030009;5752.482276595127;4452.510223875371;4502.279635258343;4507.323901414748;4511.526289739596;4540.72755634663;4013.1973605279063;4019.9397009044887;4028.2144981728284;4053.067794407244;4072.1041370898947;5341.439148889311;5396.325459317585;5539.9169012464845;5592.8042518512475;5607.271054762855;5836.124581312837;5908.734106965197;5917.911231331534;5934.258217722785;5935.1987481690285;5467.781288748405;5490.780368785204;5491.533219676372;5493.7802487900835;5511.641743286754;4982.427020892644;5061.746912654353;5106.336176297058;5115.283860259567;5153.484651534892;6042.8791424170395;6046.879062418727;6048.0625503047095;6052.727627256784;6097.939020609826;5795.94204057954;5802.100674395476;5807.796727114531;5812.82561523155;5826.7086645666695;5864.706764661835;5989.670568118795;6007.12896629995;6010.368911264254;6098.288897731724;5736.254286942838;5850.707464626837;5859.091840764689;5880.852978675567;5890.852728681817;5874.236349274567;5906.881862362643;5958;5964.194833697396;5965.045592705146;5571.415001424792;5641.041023026178;5705.115707065412;5709.54323654115;5776.046952252999;5736.340320863154;5812.476877081083;5825.330087039875;5840.883182336414;5843.094320380348;5472.097103977799;5717.599768016199;5718.99917514425;5719.914201286901;5725.971370143123;5695.943040569541;5710.37185909558;5773.04744717117;5790.652560846363;5840.795572154954;5669.6314739541795;5677.574181936454;5678.943210567842;5683.3748287687495;5740.454656807597 18:48:59 INFO - 2015-11-23 18:48:59,860 DEBUG : BROWSER_OUTPUT: |1;ext.html;574.7902015764246;578.4649199490473;586.8562202260447;590.4449817171858;606.9180660610816;30798.69201307986;38804;39591.40612890809;39600.40599391011;39678.80160599197;42487.575124248746;42763.14473710524;42815.3577696334;42873.78563107188;42984.570154298526;7427.628618569077;7606.04924384452;8573.828523429527;8752.956235218815;8758.430702004382;10161.745956351077;10495.055445009948;10530.736731581695;10537.947310263438;10570.577176912915;10266;10351.378917264989;10353.534090965924;10367.06696397324;10375;7626.961865190694;7862.724804631867;10359.533820978037;10398.948005259925;10430.947845260802;10120.595176192943;10136.847947280796;10144.239182061301;10150.492475376202;10260.076593106656;38449.42325865114;38534.61465385338;38742.61257387418;38921.41617875734;38950.61049389498;3227.757918156124;3238.627557830856;3293.901182964515;4022.2960981828064;4102.220578090153;29900.85049574738;29995.100146995625;30140.0957971259;30203.935492533412;30308.090757277318;3831.885043448701;3903.1413089120347;3976.80115994202;3978.5026871641044;4081.8367265309353;258662;260052;260146;261425;261993;56023;56249;56338.154927676114;56404.71797641068;56432.15351769727;58729.4127058724;59555.404445954984;65804.34195657982;66600.00599946028;66773.66613167;13957.720845583031;13990.510332138327;14230.644233894029;14237.786433203302;14255.643608909653;10679.626213082505;10763.677089687322;10801.945990269998;10802.567897284178;10809.945950270197;18898.716519252222;18918.81081189198;19032.143553540125;19048.71426928597;19083.236670533024;7422.554646721214;7605.771826845204;7627.656755446018;7703;7759.379249660014;925.708401853414;945.7304668169538;970.2335155227432;992.1517102876936;1006.6376104602338;27342.953650590516;27476.45047099087;27849.860750696116;27868.860655696997;27977.30056748557;984.8719666443317;1016.5679586175846;1023.3399457349967;1032.932859364139;1157.1263695909533;14595.416183352547;14692.779608305884;14801.259937003322;14807.925960370128;15651.373945042298;240862;243635;243693;244038;244103;48481.5151848484;48573.51426485761;48573.757131214115;48741.51258487441;48755;1301.4273719563362;1308.1562392256942;1319.874611911886;1323.993380033113;1329.1227789658778;28318.716812832434;28880.2779930495;28894.277643059093;29352.70647293585;29439.26401839971;44898.77550612226;45355.31967020563;45365;45413;45483.54516454926;281746;285795;286007;286022;287654;27759.861200693867;31227.219319516284;31468.842655786575;31484.370312594077;31752;37140.257194856495;38245.23509529849;38610.42084368681;38837.6116238834;39191.2161756769;34738.65261347455;35338.64661353456;35415.82292088523;35756;36232.456513151796;28408.57387139236;33169.50245746267;34260;35743.285134296646;36051.63948360483;32109.357812843144;32256.354872901942;32338.35323293568;32433.35133297368;35745.28509429849;28426.857865711365;29573.852130739207;30314.696853031186;30658.540121897742;30837.537436938914;25376.619350710123;25877;26898.462030758885;27056.45887082208;27168.320791980357 18:48:59 INFO - 2015-11-23 18:48:59,863 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.71977138060936;355.61060638600725;356.7681007345226;356.7805799433349;357.7513628028518;14331.570052898403;14475.493357732455;16420.58948526286;16684.249208785604;17389.304427822903;15622;16827.831721682778;16899.070551119683;16908.83091169088;17644.735328970044;5312.840614781563;5466.781328746865;5468.808591699291;5473.480019398151;5480;3999.540052893911;4131.607497287753;5405.270288511059;5422.701751403672;5803.274590676166;5831.329397119322;5862.560307976897;5889.9705501472645;5901.586888917777;5917.260342457193;5725.227094342273;5860.09168578871;5868.471837534642;5868.647881127146;5883.88232235355;5178.715170665612;5185.299984502081;5201.55786758126;5204.9479505204845;5216.061109000379;18621.7206741899;19291;20448;21329.893350533304;21379.572408551743;2150.064721846002;2150.516133869882;2192.353255789546;2306.3196357074703;2368.171140100968;9057;9220.308476864195;9695.175910047654;10125.797484050277;12948.546800861925;1829.4694538583794;1834.660587791263;1894.9242030318908;1902.5624106455455;1945.7470528831302;59993;62234.066489003155;62731.37268627301;63080.36919630791;63338;9566.521673916277;9620.615175393046;9623.133917947418;9646.807063858683;9695.224382049419;12562.937185314198;12588.37058147089;12601.18092323996;12602.55891043809;12609.684757880943;6994.090768200102;7203.351698347069;7246.202917679051;7249.673764680602;7258.165310989304;6930.688119034656;6933.68798404073;6991.790246292518;7041.3310735480045;7117.4306055515435;4066.4510291110914;4070.3487442009728;4075.8777236682945;4224.155168966219;4229.7250678706405;1566.749320108777;1598.5044636162827;1614.564067701714;1616.5392863034215;1693.3903794633923;843.9451435656663;886.4902680958475;904.5703290936884;941.6845356805546;974.5370948799264;15594.376224950875;16587.336506539843;16786.4124755633;16827.41104061352;17103.48689539316;937.7702462896634;949.2595775295281;974.0989584634326;994.3288280410839;1003.9397636141855;9184.371499102795;9545.474998875128;9596;9621.470819104876;9722.951385243028;472049;475017;476530;485165;485983;75061.62469187619;75106.62446687731;75787;76054;76215;1102.6251074634663;1107.7839821234782;1144.1361771862357;1158.4323681396056;1158.6408213453856;14761.409543618134;14829.703405932038;14930.178840163895;15040.699186016438;15207.239638018276;32800.83599581987;33169.50245746364;33287.66712332846;36055;36775.632243677224;41978.790106050496;47311.52688473071;47946.280805788636;48695.51304487052;50532;9109.498977556297;9717.416954982724;9876.950615246878;9879.703608891745;9924.354916930377;11077.113830893348;11135.554577816796;11187.496562654538;11192.776144477228;11197.496112675111;13520.797188042385;14071.577852664437;14164.78752818688;14432.206228657524;14477.855221447651;13924.651883702583;14032.649183770487;14281.42874285017;14445.06107103035;14505.129692218501;14367.64080897944;14487.20320382389;14680.706385872436;14994.500549944918;15028.474003409829;9030.36787424874;9106.544672766468;9183.357164998386;9261.629534818532;9290.070992900926;9875.950620246853;9887.802243955224;10217;14558.781618275947;14710.55868323952 18:48:59 INFO - 2015-11-23 18:48:59,865 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1298.5130576033987;1423.3452611798573;1485.2990285745616;1606.389571962654;1718.1151706870955;6634.635095069769;7257.7096916123355;7542.170361260257;7549.0186275784135;7549.471536992412;7560.621968901561;7579.052618422697;7583.2795884391035;7627.694892204319;7634.732784352549;1954.6188493243824;1992.7608686957585;2270.375646697163;2271.806896413807;2293.9541209175804;2244.1135751378197;2295.494991101963;2297.3337732057767;2298.138198175684;2298.276043046435;2223.3885681437573;2259.7740225977436;2260.367097212783;2262.4117729390314;2263.54729054189;1958.177565422526;2000.2299114840746;2003.268806885491;2216.1246307708525;2248.1457046322457;2250.696156018933;2259.096361455431;2259.954800903989;2260.672202530624;2261.366817291161;7556.168821429638;7700.383969282444;7700.614969251515;7703.998480197594;7709.653065612061;4607.470140933769;4679.485256621769;4711.599514041311;4714.387129673155;4716.434027916637;3117.9064628061196;3147.5278708193723;3238.6559577775197;3247.025892232321;3268.297316077031;1003.3929472669004;1011.0294117646994;1015.5531566110888;1023.2223510132278;1034.5137785240925;10577.36535807854;10602.893971060268;10611.098056665192;10613.681589552249;10617.46912654372;4138.461999939988;4214.726042807269;4261.4673165854265;4278.9730464688255;4282.036541778138;9111.225545828545;9130.589123489459;9144.222741066951;9144.63421463134;9146.54267286633;4756.762161891892;4758.1673207188605;4777.665563410597;4778.1399348117675;4779.95220047802;4337.436133302714;4344.56554344563;4349.151915376535;4351.412559304516;4353.869383918487;3671.7796932184156;3685.391910334765;3690.9630903691154;3709.3323201823932;3712.2204337088974;1156.4795841871191;1165.4930105404007;1171.8300846377228;1173.4895320535595;1180.167981572989;1079.8974097460728;1291.8256035435095;1299.1685321394316;1301.707115898934;1337.2778699502355;7357.337839594462;7381.483296169216;7394.704211831467;7410.555366678017;7421.406287496988;1086.2667699303097;1115.8437818705388;1115.8605174353206;1129.5538262386312;1140.7091191746051;5366.973165134149;5501.229827824108;5919.437653422918;6084.908726369108;6096.512279017757;8919.06349833271;9035.322350823582;9075.13786190311;9431.434113953184;9544.904550954541;9421.62313507452;9426.387284826464;9432;9438.622455101719;9442.622295108256;7206.171290301578;7212.567245965259;7357.374623156985;7378.188399276075;7389.963050184822;7340.265973402617;7358.96320518394;7364.447666425147;7373.520721153108;7376.7786966391095;8061.476004059952;8252.793680158044;8332.375071869756;8446.493210407396;8463.111373305714;8470.830583388175;8522.957385213034;8665.566721663765;8666.78333041679;8680.088590697886;6713.160854893138;6718.899216511659;6816.454683625199;6831.726730930707;6838.281980392187;7526.059242594676;7564.016677831846;7566.394688424803;7580.279873412017;7585.393168546613;7056.518131192302;7157.284271572801;7186.496945213785;7263.745768898063;7287.817804554928;6994.265602111908;7085.000198043299;7114.288571142844;7169.856602867809;7220.711171553079;7166.749163779243;7194.820129496805;7249.565026098451;7257.637118144177;7267.745628902963;6587.901181482186;6642.800715978528;6645.534812563074;6647.368499992493;6652.73389064432;6533.771318003847;6603.339666033358;6657.101291325609;6665;6668.533202675766 18:48:59 INFO - 2015-11-23 18:48:59,866 DEBUG : BROWSER_OUTPUT: |4;prototype.html;317.47299482858455;321.47278463320157;324.6347858659009;324.8765469121734;326.95749552558163;5997.52019838413;6039.1243269725865;6044.667543285118;6075.605085669434;6079.3008803987495;5962.135490353896;5966.343702192756;5968.253968253968;5969.044952807551;5969.492593129578;4324.82700691972;4420.602145806877;4436.800343984529;4567.862964111082;4581.244094724383;4550.476695180064;4551.249043907767;4584.862454126365;4598.425196850394;4598.839040633578;4539.432570928634;4544.863654090383;4551.226291530448;4552.476465206494;4553.066621342633;4326.6106050455455;4421.624161946238;4435.090806384684;4535.342375355555;4555.931661025054;3970.642642162219;3972.967028572563;3981.9845939285296;3984;3987.880363589068;5707.086866101446;5711.7715291388295;5714.828460165656;5716.657000579937;5729.598928075036;5215.478452154754;5313.096773548467;5324.840254792362;5329.22726204702;5334.706591137486;6361.950278204091;6365.236171659384;6367.204099487564;6378;6379.36206379363;4366.672499562545;4377.233984052778;4379.824807007716;4419.049904270597;4430.335449682573;6624.73501059957;6627.73489060437;6634.402903738638;6637.103990961206;6643.93356066438;4430.069685366109;4488.506264310923;4521.751303678264;4527.637788976874;4535.614472769786;4551.271796512542;4560.840370587013;4567.794449249792;4589.747563884019;4596;5274.367075950834;5305.36335639718;5306.6550674206055;5307.893842123136;5310.893782124336;6398.744050237939;6400.743970241138;6402.775902843378;6406.967965160145;6412.871742565123;1538.7845701601784;1570.3169121432213;1573.590866374728;1578.0610536730626;1580.0677600215927;1027.091024443358;1034.0280136671504;1034.5137785240925;1045.6601604478574;1054.4147997861107;6099.237595300588;6119.5410344224365;6134.202553667995;6156.630602163885;6165.660888651078;6551.1155993941165;6554.770583029666;6557.409833115043;6558.901616475757;6559.114519539801;6020.518358531307;6145.231846019247;6148.508119350441;6157.599756015845;6160.753569857246;3538.0093573799377;3538.8230588470988;3561.074120728629;3561.71506279497;3564.8574057037945;6770.32296770319;6778.796636100925;6782.491313151533;6784.660766961632;6794.864102717918;6693.866122677519;6704.296048914891;6704.731810727614;6708.262091169968;6708.865822683519;6699.698513566901;6872.175338959257;6945.548539344926;6951.965240173767;6953.061336719478;6665.20017597872;6669.633170175687;6674.8998765017595;6676.532642714963;6688.598684078971;6461.515386345949;6625.734970601122;6627.072209890715;6634.568753031038;6636.701348439428;6931.480138989697;6936.583804971718;6944.965275173592;6945.027696122446;6952.5133240674695;4404.339349097661;4418;4420;4421.182081314967;4428.977855110704;6667.299933506912;6667.533272670867;6672.966635166793;6678.966605166943;6680.966595166993;6304.698784943371;6476.255230648348;6479.8056058318325;6485.77299794505;6493.318201588954;6453.386928241809;6453.806385808433;6459.031145328238;6468.223813142453;6472.223333200046;6203.131561581473;6340.207474065742;6344.333844946399;6364.204474440695;6367.108604795239;6500.512461565307;6504.349565043458;6508.8047358579315;6511.218653761579;6512.772052978123;6306.621602703853;6340.144080549251;6345.746170153142;6348.9047664286;6355.809325720235 18:48:59 INFO - 2015-11-23 18:48:59,866 DEBUG : BROWSER_OUTPUT: |5;yui.html;744.4305106593457;765.5483264873724;778.1440415542907;814.4217605500095;861.4278940932797;14014.649633759149;14168.57494275172;14233.074850134733;14244.572662820106;14359.712805743879;14323.498677546288;14729.631759206026;14734.852651473482;14737.48418805342;14741.41034358627;678.4097834883659;696.1611258433576;814.2834305810893;828.7596596986866;830.3647709502205;803.2529747334976;828.1759649149105;833.62903507939;833.6790335720731;833.737372727592;856.4903882190087;857.4983634573782;861.5347712235385;863.3179787967498;864.0754392799707;694.920084190317;714.7784186902077;715.4455297144729;716.0333549707905;856.1139220906363;605.8121982185537;614.1463365921372;619.6313193649771;620.1411045701691;621.2203684376125;13867.37596808146;13993.650158746113;13998.370073346723;14031.508897188549;14037.438502459889;12443.315617641027;12621.116521843474;12772.297523636196;13030;13395.397207125703;10028.197744180448;10433.686989390331;10493.802716508902;10639.734006649818;10683.35899846004;3649.0613714820265;3699.85200591976;3714.739968202227;3715.0526615713093;3839.4816699745456;47677.28484072707;47857.282140767915;48135.277970830466;48384;48407;10291.485425728684;10460.476976151162;10517.579296828042;10612.787744245226;10622.521986510626;16652.916735416246;16695.24871380791;16705.916470417815;16852.410165644145;16873.662526749395;7803.141654418009;7931.127575966639;7983.960080199678;8036.357091432671;8108.797280068045;4365.388845561624;4432.556744325606;4458.7547684877645;4466.307722303049;4482.7758612069265;2954.1285320830407;2974.866131024109;2977.865996030184;2989.043506078034;2996.6553846307515;533.557147567518;534.4922323792335;547.7261369315343;551.456815037188;556.5575367582782;547.9808206712746;593.9435753603401;636.2841802971657;690.5442408010689;715.4348065028673;8143.389245806469;8316;8386.706465273686;8469.915300846913;8525.887677186605;601.6871226961955;662.3244290823333;671.771597656799;683.3576438148125;728.661172554754;4024;4081.8163182656854;4191.622753952098;4231.597998190167;4287.699861009762;57222.42777572254;57519.424805751405;58030.41969580334;58186.418135818945;58280;51627.22559161616;51650.74174629103;51804.22293665598;51808.48191518112;52543.73728131335;46896;47439.28841067387;47466.525334746904;48144.277835832494;48181.27728084082;8522.829543409222;8651.394402391772;8812.867806982773;9113.134252246025;9178.173964343241;35090.649093508735;35463.82268088746;35535.28929421346;36172.638273616925;36995.63004369922;44810;45648.31527527023;45803.54196458126;45993.31010034919;46532.30201547047;11091.500882460146;11205.43972801373;11242;11254.831177532507;11360.54557817678;11754.29474231549;11838.526458941546;11874.76250475003;12898.548550800677;13018.739625207632;11598.18812683104;11875.52497900109;12080.637580872588;12221.938890305491;12453.25280483174;11726.355050472306;12119.394030298627;12135;12238.877611223774;12464.813027804408;7925.642427556041;10085;10765.300255483682;11697.23967942081;11966.341851198267;10213.642522511973;10308.690739277834;10477.37135771856;10478.161747060065;10641.095506882002;9749.268804839807;9955.303128780915;10044.196464282986;10114.747131321776;10118.898811011795 18:48:59 INFO - 2015-11-23 18:48:59,866 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:48:59 INFO - 2015-11-23 18:48:59,866 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,871 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333339855__endTimestamp 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,867 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5430.05 Median:5677.57 stddev:605.86 (10.7%) stddev-sans-first:581.01 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: Values: 3162.3 3208.2 3245.0 3248.5 3280.7 5276.3 5366.8 5414.2 5453.0 5465.7 5342.1 5371.8 5443.8 5479.3 5488.0 4954.6 4977.2 4978.4 4980.8 5051.6 5023.3 5119.8 5133.9 5142.4 5175.0 4831.0 4945.3 4968.4 5057.0 5066.8 4909.9 4919.8 4929.6 4929.9 4940.5 4897.5 4919.0 4926.6 4927.0 4931.4 5144.9 5160.1 5207.8 5237.0 5237.5 5812.2 5821.1 5822.4 5828.9 5868.3 5655.5 5838.3 5840.2 5858.1 5872.9 5368.2 5452.3 5473.1 5479.5 5519.4 5915.9 5948.1 6009.0 6015.8 6023.5 5646.8 5776.3 5785.5 5823.1 5823.2 5792.2 5911.9 5912.1 5914.2 5967.9 5691.7 5801.8 5807.0 5808.2 5813.9 5610.0 5716.2 5721.4 5738.8 5752.5 4452.5 4502.3 4507.3 4511.5 4540.7 4013.2 4019.9 4028.2 4053.1 4072.1 5341.4 5396.3 5539.9 5592.8 5607.3 5836.1 5908.7 5917.9 5934.3 5935.2 5467.8 5490.8 5491.5 5493.8 5511.6 4982.4 5061.7 5106.3 5115.3 5153.5 6042.9 6046.9 6048.1 6052.7 6097.9 5795.9 5802.1 5807.8 5812.8 5826.7 5864.7 5989.7 6007.1 6010.4 6098.3 5736.3 5850.7 5859.1 5880.9 5890.9 5874.2 5906.9 5958.0 5964.2 5965.0 5571.4 5641.0 5705.1 5709.5 5776.0 5736.3 5812.5 5825.3 5840.9 5843.1 5472.1 5717.6 5719.0 5719.9 5726.0 5695.9 5710.4 5773.0 5790.7 5840.8 5669.6 5677.6 5678.9 5683.4 5740.5 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43412.57 Median:31032.38 stddev:68611.51 (221.1%) stddev-sans-first:68728.59 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: Values: 574.8 578.5 586.9 590.4 606.9 30798.7 38804.0 39591.4 39600.4 39678.8 42487.6 42763.1 42815.4 42873.8 42984.6 7427.6 7606.0 8573.8 8753.0 8758.4 10161.7 10495.1 10530.7 10537.9 10570.6 10266.0 10351.4 10353.5 10367.1 10375.0 7627.0 7862.7 10359.5 10398.9 10430.9 10120.6 10136.8 10144.2 10150.5 10260.1 38449.4 38534.6 38742.6 38921.4 38950.6 3227.8 3238.6 3293.9 4022.3 4102.2 29900.9 29995.1 30140.1 30203.9 30308.1 3831.9 3903.1 3976.8 3978.5 4081.8 258662.0 260052.0 260146.0 261425.0 261993.0 56023.0 56249.0 56338.2 56404.7 56432.2 58729.4 59555.4 65804.3 66600.0 66773.7 13957.7 13990.5 14230.6 14237.8 14255.6 10679.6 10763.7 10801.9 10802.6 10809.9 18898.7 18918.8 19032.1 19048.7 19083.2 7422.6 7605.8 7627.7 7703.0 7759.4 925.7 945.7 970.2 992.2 1006.6 27343.0 27476.5 27849.9 27868.9 27977.3 984.9 1016.6 1023.3 1032.9 1157.1 14595.4 14692.8 14801.3 14807.9 15651.4 240862.0 243635.0 243693.0 244038.0 244103.0 48481.5 48573.5 48573.8 48741.5 48755.0 1301.4 1308.2 1319.9 1324.0 1329.1 28318.7 28880.3 28894.3 29352.7 29439.3 44898.8 45355.3 45365.0 45413.0 45483.5 281746.0 285795.0 286007.0 286022.0 287654.0 27759.9 31227.2 31468.8 31484.4 31752.0 37140.3 38245.2 38610.4 38837.6 39191.2 34738.7 35338.6 35415.8 35756.0 36232.5 28408.6 33169.5 34260.0 35743.3 36051.6 32109.4 32256.4 32338.4 32433.4 35745.3 28426.9 29573.9 30314.7 30658.5 30837.5 25376.6 25877.0 26898.5 27056.5 27168.3 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26795.72 Median:23055.20 stddev:78365.21 (339.9%) stddev-sans-first:78559.89 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: Values: 354.7 355.6 356.8 356.8 357.8 14331.6 14475.5 16420.6 16684.2 17389.3 15622.0 16827.8 16899.1 16908.8 17644.7 5312.8 5466.8 5468.8 5473.5 5480.0 3999.5 4131.6 5405.3 5422.7 5803.3 5831.3 5862.6 5890.0 5901.6 5917.3 5725.2 5860.1 5868.5 5868.6 5883.9 5178.7 5185.3 5201.6 5204.9 5216.1 18621.7 19291.0 20448.0 21329.9 21379.6 2150.1 2150.5 2192.4 2306.3 2368.2 9057.0 9220.3 9695.2 10125.8 12948.5 1829.5 1834.7 1894.9 1902.6 1945.7 59993.0 62234.1 62731.4 63080.4 63338.0 9566.5 9620.6 9623.1 9646.8 9695.2 12562.9 12588.4 12601.2 12602.6 12609.7 6994.1 7203.4 7246.2 7249.7 7258.2 6930.7 6933.7 6991.8 7041.3 7117.4 4066.5 4070.3 4075.9 4224.2 4229.7 1566.7 1598.5 1614.6 1616.5 1693.4 843.9 886.5 904.6 941.7 974.5 15594.4 16587.3 16786.4 16827.4 17103.5 937.8 949.3 974.1 994.3 1003.9 9184.4 9545.5 9596.0 9621.5 9723.0 472049.0 475017.0 476530.0 485165.0 485983.0 75061.6 75106.6 75787.0 76054.0 76215.0 1102.6 1107.8 1144.1 1158.4 1158.6 14761.4 14829.7 14930.2 15040.7 15207.2 32800.8 33169.5 33287.7 36055.0 36775.6 41978.8 47311.5 47946.3 48695.5 50532.0 9109.5 9717.4 9877.0 9879.7 9924.4 11077.1 11135.6 11187.5 11192.8 11197.5 13520.8 14071.6 14164.8 14432.2 14477.9 13924.7 14032.6 14281.4 14445.1 14505.1 14367.6 14487.2 14680.7 14994.5 15028.5 9030.4 9106.5 9183.4 9261.6 9290.1 9876.0 9887.8 10217.0 14558.8 14710.6 18:48:59 INFO - 2015-11-23 18:48:59,869 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,870 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5469.88 Median:6560.84 stddev:2820.95 (43.0%) stddev-sans-first:2811.43 18:48:59 INFO - 2015-11-23 18:48:59,871 DEBUG : BROWSER_OUTPUT: Values: 1298.5 1423.3 1485.3 1606.4 1718.1 6634.6 7257.7 7542.2 7549.0 7549.5 7560.6 7579.1 7583.3 7627.7 7634.7 1954.6 1992.8 2270.4 2271.8 2294.0 2244.1 2295.5 2297.3 2298.1 2298.3 2223.4 2259.8 2260.4 2262.4 2263.5 1958.2 2000.2 2003.3 2216.1 2248.1 2250.7 2259.1 2260.0 2260.7 2261.4 7556.2 7700.4 7700.6 7704.0 7709.7 4607.5 4679.5 4711.6 4714.4 4716.4 3117.9 3147.5 3238.7 3247.0 3268.3 1003.4 1011.0 1015.6 1023.2 1034.5 10577.4 10602.9 10611.1 10613.7 10617.5 4138.5 4214.7 4261.5 4279.0 4282.0 9111.2 9130.6 9144.2 9144.6 9146.5 4756.8 4758.2 4777.7 4778.1 4780.0 4337.4 4344.6 4349.2 4351.4 4353.9 3671.8 3685.4 3691.0 3709.3 3712.2 1156.5 1165.5 1171.8 1173.5 1180.2 1079.9 1291.8 1299.2 1301.7 1337.3 7357.3 7381.5 7394.7 7410.6 7421.4 1086.3 1115.8 1115.9 1129.6 1140.7 5367.0 5501.2 5919.4 6084.9 6096.5 8919.1 9035.3 9075.1 9431.4 9544.9 9421.6 9426.4 9432.0 9438.6 9442.6 7206.2 7212.6 7357.4 7378.2 7390.0 7340.3 7359.0 7364.4 7373.5 7376.8 8061.5 8252.8 8332.4 8446.5 8463.1 8470.8 8523.0 8665.6 8666.8 8680.1 6713.2 6718.9 6816.5 6831.7 6838.3 7526.1 7564.0 7566.4 7580.3 7585.4 7056.5 7157.3 7186.5 7263.7 7287.8 6994.3 7085.0 7114.3 7169.9 7220.7 7166.7 7194.8 7249.6 7257.6 7267.7 6587.9 6642.8 6645.5 6647.4 6652.7 6533.8 6603.3 6657.1 6665.0 6668.5 18:48:59 INFO - 2015-11-23 18:48:59,871 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,871 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5341.54 Median:6089.27 stddev:1639.80 (26.9%) stddev-sans-first:1600.45 18:48:59 INFO - 2015-11-23 18:48:59,871 DEBUG : BROWSER_OUTPUT: Values: 317.5 321.5 324.6 324.9 327.0 5997.5 6039.1 6044.7 6075.6 6079.3 5962.1 5966.3 5968.3 5969.0 5969.5 4324.8 4420.6 4436.8 4567.9 4581.2 4550.5 4551.2 4584.9 4598.4 4598.8 4539.4 4544.9 4551.2 4552.5 4553.1 4326.6 4421.6 4435.1 4535.3 4555.9 3970.6 3973.0 3982.0 3984.0 3987.9 5707.1 5711.8 5714.8 5716.7 5729.6 5215.5 5313.1 5324.8 5329.2 5334.7 6362.0 6365.2 6367.2 6378.0 6379.4 4366.7 4377.2 4379.8 4419.0 4430.3 6624.7 6627.7 6634.4 6637.1 6643.9 4430.1 4488.5 4521.8 4527.6 4535.6 4551.3 4560.8 4567.8 4589.7 4596.0 5274.4 5305.4 5306.7 5307.9 5310.9 6398.7 6400.7 6402.8 6407.0 6412.9 1538.8 1570.3 1573.6 1578.1 1580.1 1027.1 1034.0 1034.5 1045.7 1054.4 6099.2 6119.5 6134.2 6156.6 6165.7 6551.1 6554.8 6557.4 6558.9 6559.1 6020.5 6145.2 6148.5 6157.6 6160.8 3538.0 3538.8 3561.1 3561.7 3564.9 6770.3 6778.8 6782.5 6784.7 6794.9 6693.9 6704.3 6704.7 6708.3 6708.9 6699.7 6872.2 6945.5 6952.0 6953.1 6665.2 6669.6 6674.9 6676.5 6688.6 6461.5 6625.7 6627.1 6634.6 6636.7 6931.5 6936.6 6945.0 6945.0 6952.5 4404.3 4418.0 4420.0 4421.2 4429.0 6667.3 6667.5 6673.0 6679.0 6681.0 6304.7 6476.3 6479.8 6485.8 6493.3 6453.4 6453.8 6459.0 6468.2 6472.2 6203.1 6340.2 6344.3 6364.2 6367.1 6500.5 6504.3 6508.8 6511.2 6512.8 6306.6 6340.1 6345.7 6348.9 6355.8 18:48:59 INFO - 2015-11-23 18:48:59,871 DEBUG : BROWSER_OUTPUT: 18:48:59 INFO - 2015-11-23 18:48:59,871 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14056.34 Median:24638.15 stddev:16145.41 (65.5%) stddev-sans-first:16159.75 18:48:59 INFO - 2015-11-23 18:48:59,872 DEBUG : BROWSER_OUTPUT: Values: 744.4 765.5 778.1 814.4 861.4 14014.6 14168.6 14233.1 14244.6 14359.7 14323.5 14729.6 14734.9 14737.5 14741.4 678.4 696.2 814.3 828.8 830.4 803.3 828.2 833.6 833.7 833.7 856.5 857.5 861.5 863.3 864.1 694.9 714.8 715.4 716.0 856.1 605.8 614.1 619.6 620.1 621.2 13867.4 13993.7 13998.4 14031.5 14037.4 12443.3 12621.1 12772.3 13030.0 13395.4 10028.2 10433.7 10493.8 10639.7 10683.4 3649.1 3699.9 3714.7 3715.1 3839.5 47677.3 47857.3 48135.3 48384.0 48407.0 10291.5 10460.5 10517.6 10612.8 10622.5 16652.9 16695.2 16705.9 16852.4 16873.7 7803.1 7931.1 7984.0 8036.4 8108.8 4365.4 4432.6 4458.8 4466.3 4482.8 2954.1 2974.9 2977.9 2989.0 2996.7 533.6 534.5 547.7 551.5 556.6 548.0 593.9 636.3 690.5 715.4 8143.4 8316.0 8386.7 8469.9 8525.9 601.7 662.3 671.8 683.4 728.7 4024.0 4081.8 4191.6 4231.6 4287.7 57222.4 57519.4 58030.4 58186.4 58280.0 51627.2 51650.7 51804.2 51808.5 52543.7 46896.0 47439.3 47466.5 48144.3 48181.3 8522.8 8651.4 8812.9 9113.1 9178.2 35090.6 35463.8 35535.3 36172.6 36995.6 44810.0 45648.3 45803.5 45993.3 46532.3 11091.5 11205.4 11242.0 11254.8 11360.5 11754.3 11838.5 11874.8 12898.5 13018.7 11598.2 11875.5 12080.6 12221.9 12453.3 11726.4 12119.4 12135.0 12238.9 12464.8 7925.6 10085.0 10765.3 11697.2 11966.3 10213.6 10308.7 10477.4 10478.2 10641.1 9749.3 9955.3 10044.2 10114.7 10118.9 18:48:59 INFO - 2015-11-23 18:48:59,873 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:48:59 INFO - 2015-11-23 18:48:59,873 DEBUG : BROWSER_OUTPUT: 18:49:00 INFO - 2015-11-23 18:49:00,505 INFO : Browser exited with error code: 0 18:49:00 INFO - 2015-11-23 18:49:00,529 INFO : Completed test dromaeo_css (00:18:33) 18:49:00 INFO - 2015-11-23 18:49:00,529 INFO : Starting test kraken 18:49:00 INFO - 2015-11-23 18:49:00,529 DEBUG : operating with platform_type : linux_ 18:49:00 INFO - 2015-11-23 18:49:00,529 INFO : Initialising browser for kraken test... 18:49:00 INFO - 2015-11-23 18:49:00,535 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxIE8sI/profile http://localhost:44409/getInfo.html 18:49:04 INFO - 2015-11-23 18:49:04,505 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:49:04 INFO - 2015-11-23 18:49:04,505 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:49:04 INFO - 2015-11-23 18:49:04,505 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:49:04 INFO - 2015-11-23 18:49:04,505 DEBUG : BROWSER_OUTPUT: __metrics 18:49:04 INFO - 2015-11-23 18:49:04,690 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:05 INFO - 2015-11-23 18:49:05,905 INFO : Browser initialized. 18:49:05 INFO - 2015-11-23 18:49:05,906 INFO : Running cycle 1/1 for kraken test... 18:49:05 INFO - 2015-11-23 18:49:05,906 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxIE8sI/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:49:17 INFO - 2015-11-23 18:49:17,726 DEBUG : BROWSER_OUTPUT: RSS: Main: 139866112 18:49:17 INFO - 2015-11-23 18:49:17,726 DEBUG : BROWSER_OUTPUT: 18:49:20 INFO - 2015-11-23 18:49:20,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44409/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:20 INFO - 2015-11-23 18:49:20,768 DEBUG : BROWSER_OUTPUT: RSS: Main: 178671616 18:49:20 INFO - 2015-11-23 18:49:20,768 DEBUG : BROWSER_OUTPUT: 18:49:23 INFO - 2015-11-23 18:49:23,365 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44409/tests/kraken/driver.html?testName=audio-dft) 18:49:23 INFO - 2015-11-23 18:49:23,933 DEBUG : BROWSER_OUTPUT: RSS: Main: 168939520 18:49:23 INFO - 2015-11-23 18:49:23,933 DEBUG : BROWSER_OUTPUT: 18:49:26 INFO - 2015-11-23 18:49:26,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44409/tests/kraken/driver.html?testName=audio-fft) 18:49:27 INFO - 2015-11-23 18:49:27,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 152322048 18:49:27 INFO - 2015-11-23 18:49:27,373 DEBUG : BROWSER_OUTPUT: 18:49:29 INFO - 2015-11-23 18:49:29,357 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44409/tests/kraken/driver.html?testName=audio-oscillator) 18:49:29 INFO - 2015-11-23 18:49:29,918 DEBUG : BROWSER_OUTPUT: RSS: Main: 153546752 18:49:29 INFO - 2015-11-23 18:49:29,918 DEBUG : BROWSER_OUTPUT: 18:49:32 INFO - 2015-11-23 18:49:32,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44409/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:32 INFO - 2015-11-23 18:49:32,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 156147712 18:49:32 INFO - 2015-11-23 18:49:32,658 DEBUG : BROWSER_OUTPUT: 18:49:35 INFO - 2015-11-23 18:49:35,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44409/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:36 INFO - 2015-11-23 18:49:36,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 155684864 18:49:36 INFO - 2015-11-23 18:49:36,141 DEBUG : BROWSER_OUTPUT: 18:49:39 INFO - 2015-11-23 18:49:39,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44409/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:40 INFO - 2015-11-23 18:49:40,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 156844032 18:49:40 INFO - 2015-11-23 18:49:40,076 DEBUG : BROWSER_OUTPUT: 18:49:42 INFO - 2015-11-23 18:49:42,853 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44409/tests/kraken/driver.html?testName=json-parse-financial) 18:49:43 INFO - 2015-11-23 18:49:43,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 155340800 18:49:43 INFO - 2015-11-23 18:49:43,426 DEBUG : BROWSER_OUTPUT: 18:49:44 INFO - 2015-11-23 18:49:44,978 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44409/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:49:45 INFO - 2015-11-23 18:49:45,540 DEBUG : BROWSER_OUTPUT: RSS: Main: 169017344 18:49:45 INFO - 2015-11-23 18:49:45,540 DEBUG : BROWSER_OUTPUT: 18:49:47 INFO - 2015-11-23 18:49:47,336 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:49:47 INFO - 2015-11-23 18:49:47,925 DEBUG : BROWSER_OUTPUT: RSS: Main: 168939520 18:49:47 INFO - 2015-11-23 18:49:47,925 DEBUG : BROWSER_OUTPUT: 18:49:49 INFO - 2015-11-23 18:49:49,978 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:49:50 INFO - 2015-11-23 18:49:50,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 174948352 18:49:50 INFO - 2015-11-23 18:49:50,551 DEBUG : BROWSER_OUTPUT: 18:49:52 INFO - 2015-11-23 18:49:52,931 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:49:53 INFO - 2015-11-23 18:49:53,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 183652352 18:49:53 INFO - 2015-11-23 18:49:53,512 DEBUG : BROWSER_OUTPUT: 18:49:56 INFO - 2015-11-23 18:49:56,067 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:49:56 INFO - 2015-11-23 18:49:56,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 182616064 18:49:56 INFO - 2015-11-23 18:49:56,632 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44409/tests/kraken/driver.html?testName=ai-astar) 18:49:58 INFO - 2015-11-23 18:49:58,507 DEBUG : BROWSER_OUTPUT: RSS: Main: 240631808 18:49:58 INFO - 2015-11-23 18:49:58,507 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,508 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:49:58 INFO - 2015-11-23 18:49:58,508 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:49:58 INFO - 2015-11-23 18:49:58,508 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;109;105;110;110;110;99;109;111;103 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;124;148;162;125;158;157;125;170;126 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;160;160;161;160;162;163;164;163 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;89;101;71;70;70;93;98;70 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;134;93;94;94;141;92;92;134 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;109;109;109;110;110;109;110;109;111 18:49:58 INFO - 2015-11-23 18:49:58,509 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;146;146;145;146;146;145;145 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;58;57;58;59;60;59;58;60;55 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;68;69;69;69;69;68;68;69 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;83;86;83;83;84;85;86;88;87 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;122;116;114;118;116;121;128;118;122 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;152;150;148;161;140;143;161;149;146 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;65;65;65;66;65;64;67;65 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,914 18:49:58 INFO - 2015-11-23 18:49:58,510 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:49:58 INFO - 2015-11-23 18:49:58,511 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333398507__endTimestamp 18:49:58 INFO - 2015-11-23 18:49:58,511 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:110.00 stddev:3.95 (3.6%) stddev-sans-first:4.09 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 105.0 110.0 110.0 110.0 99.0 109.0 111.0 103.0 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,512 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.30 Median:157.50 stddev:18.28 (11.6%) stddev-sans-first:18.80 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: Values: 158.0 124.0 148.0 162.0 125.0 158.0 157.0 125.0 170.0 126.0 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 160.0 160.0 161.0 160.0 162.0 163.0 164.0 163.0 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.40 Median:71.50 stddev:13.16 (18.4%) stddev-sans-first:13.41 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 89.0 101.0 71.0 70.0 70.0 93.0 98.0 70.0 18:49:58 INFO - 2015-11-23 18:49:58,513 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.90 Median:92.50 stddev:21.10 (22.8%) stddev-sans-first:21.77 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 134.0 93.0 94.0 94.0 141.0 92.0 92.0 134.0 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.82 (0.7%) stddev-sans-first:0.73 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: Values: 111.0 109.0 109.0 109.0 110.0 110.0 109.0 110.0 109.0 111.0 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:49:58 INFO - 2015-11-23 18:49:58,514 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 18:49:58 INFO - 2015-11-23 18:49:58,515 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,515 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 18:49:58 INFO - 2015-11-23 18:49:58,515 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 18:49:58 INFO - 2015-11-23 18:49:58,515 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,515 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.50 stddev:1.48 (2.5%) stddev-sans-first:1.56 18:49:58 INFO - 2015-11-23 18:49:58,515 DEBUG : BROWSER_OUTPUT: Values: 58.0 58.0 57.0 58.0 59.0 60.0 59.0 58.0 60.0 55.0 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 68.0 69.0 69.0 69.0 69.0 68.0 68.0 69.0 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.50 stddev:2.00 (2.3%) stddev-sans-first:1.87 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: Values: 82.0 83.0 86.0 83.0 83.0 84.0 85.0 86.0 88.0 87.0 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,516 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.10 Median:121.50 stddev:4.53 (3.7%) stddev-sans-first:4.28 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: Values: 126.0 122.0 116.0 114.0 118.0 116.0 121.0 128.0 118.0 122.0 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.30 Median:149.50 stddev:7.15 (4.8%) stddev-sans-first:7.21 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: Values: 143.0 152.0 150.0 148.0 161.0 140.0 143.0 161.0 149.0 146.0 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 65.0 65.0 65.0 66.0 65.0 64.0 67.0 65.0 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:49:58 INFO - 2015-11-23 18:49:58,517 DEBUG : BROWSER_OUTPUT: 18:49:59 INFO - 2015-11-23 18:49:59,366 INFO : Browser exited with error code: 0 18:49:59 INFO - 2015-11-23 18:49:59,414 INFO : Completed test kraken (00:00:58) 18:49:59 INFO - 2015-11-23 18:49:59,415 INFO : Starting test v8_7 18:49:59 INFO - 2015-11-23 18:49:59,415 DEBUG : operating with platform_type : linux_ 18:49:59 INFO - 2015-11-23 18:49:59,415 INFO : Initialising browser for v8_7 test... 18:49:59 INFO - 2015-11-23 18:49:59,421 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzogNCT/profile http://localhost:44409/getInfo.html 18:50:03 INFO - 2015-11-23 18:50:03,238 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:50:03 INFO - 2015-11-23 18:50:03,238 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:50:03 INFO - 2015-11-23 18:50:03,238 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:50:03 INFO - 2015-11-23 18:50:03,239 DEBUG : BROWSER_OUTPUT: __metrics 18:50:03 INFO - 2015-11-23 18:50:03,356 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:50:04 INFO - 2015-11-23 18:50:04,021 DEBUG : BROWSER_OUTPUT: console.error: 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: Stack: 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 18:50:04 INFO - 2015-11-23 18:50:04,022 DEBUG : BROWSER_OUTPUT: 18:50:04 INFO - 2015-11-23 18:50:04,590 INFO : Browser initialized. 18:50:04 INFO - 2015-11-23 18:50:04,590 INFO : Running cycle 1/1 for v8_7 test... 18:50:04 INFO - 2015-11-23 18:50:04,591 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzogNCT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:50:16 INFO - 2015-11-23 18:50:16,420 DEBUG : BROWSER_OUTPUT: RSS: Main: 140042240 18:50:16 INFO - 2015-11-23 18:50:16,421 DEBUG : BROWSER_OUTPUT: 18:50:37 INFO - 2015-11-23 18:50:37,863 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44409/tests/v8_7/run.html (next: http://localhost:44409/tests/v8_7/run.html) 18:50:38 INFO - 2015-11-23 18:50:38,121 DEBUG : BROWSER_OUTPUT: RSS: Main: 619962368 18:50:38 INFO - 2015-11-23 18:50:38,121 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,121 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:50:38 INFO - 2015-11-23 18:50:38,121 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:50:38 INFO - 2015-11-23 18:50:38,121 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |0;Richards;189.07165815844206 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.89378288832586 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |2;Encrypt;285.143997718848 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5005 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1958.9041095890411 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |5;Earley;775.3679318357862 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |6;Boyer;11916.666666666666 18:50:38 INFO - 2015-11-23 18:50:38,122 DEBUG : BROWSER_OUTPUT: |7;RegExp;25375 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: |8;Splay;649.4512588766946 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333438115__endTimestamp 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:50:38 INFO - 2015-11-23 18:50:38,123 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:189.07 Median:189.07 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: Values: 189.1 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.89 Median:194.89 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: Values: 194.9 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,124 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:285.14 Median:285.14 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: Values: 285.1 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5005.00 Median:5005.00 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: Values: 5005.0 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1958.90 Median:1958.90 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,125 DEBUG : BROWSER_OUTPUT: Values: 1958.9 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:775.37 Median:775.37 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: Values: 775.4 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11916.67 Median:11916.67 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: Values: 11916.7 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,126 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25375.00 Median:25375.00 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: Values: 25375.0 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:649.45 Median:649.45 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: Values: 649.5 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 18:50:38 INFO - 2015-11-23 18:50:38,127 DEBUG : BROWSER_OUTPUT: Values: 4840.6 18:50:38 INFO - 2015-11-23 18:50:38,128 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:50:38 INFO - 2015-11-23 18:50:38,128 DEBUG : BROWSER_OUTPUT: 18:50:38 INFO - 2015-11-23 18:50:38,965 INFO : Browser exited with error code: 0 18:50:38 INFO - 2015-11-23 18:50:38,977 INFO : Completed test v8_7 (00:00:39) 18:50:39 INFO - 2015-11-23 18:50:39,070 INFO : Completed test suite (00:20:12) 18:50:39 INFO - 2015-11-23 18:50:39,070 DEBUG : Working with test: dromaeo_css 18:50:39 INFO - 2015-11-23 18:50:39,070 DEBUG : Generating results file: dromaeo_css 18:50:39 INFO - 2015-11-23 18:50:39,071 DEBUG : Working with test: kraken 18:50:39 INFO - 2015-11-23 18:50:39,071 DEBUG : Generating results file: kraken 18:50:39 INFO - 2015-11-23 18:50:39,071 INFO : javascript benchmark 18:50:39 INFO - 2015-11-23 18:50:39,072 DEBUG : Working with test: v8_7 18:50:39 INFO - 2015-11-23 18:50:39,072 DEBUG : Generating results file: v8_7 18:50:39 INFO - 2015-11-23 18:50:39,072 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:39 INFO - 2015-11-23 18:50:39,219 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 18:50:39 INFO - 2015-11-23 18:50:39,219 DEBUG : process_Request line: dromaeo_css 6991.33 graph.html#tests=[[72,201,35]] 18:50:39 INFO - 2015-11-23 18:50:39,219 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:39 INFO - 2015-11-23 18:50:39,341 DEBUG : process_Request line: kraken 1497.30 graph.html#tests=[[232,201,35]] 18:50:39 INFO - 2015-11-23 18:50:39,342 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:39 INFO - 2015-11-23 18:50:39,461 DEBUG : process_Request line: v8_7 19557.05 graph.html#tests=[[230,201,35]] 18:50:39 INFO - 2015-11-23 18:50:39,462 INFO : javascript benchmark 18:50:39 INFO - 2015-11-23 18:50:39,465 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3162.256869635635, 3208.197950512372, 3244.9940518439284, 3248.496483045129, 3280.6719328067197, 5276.287701160342, 5366.8389948301565, 5414.160805075219, 5453.0, 5465.72671366432, 5342.065138600739, 5371.785128594861, 5443.8094666686675, 5479.315085614298, 5487.972560137204, 4954.628402869781, 4977.178765503706, 4978.352814134176, 4980.800767969295, 5051.621128415365, 5023.271625614284, 5119.795208191668, 5133.922991155136, 5142.408623008346, 5174.974125129369, 4830.975845120752, 4945.258211268302, 4968.354113965162, 5056.988602279541, 5066.797328106872, 4909.901801963976, 4919.7540122993705, 4929.605631549467, 4929.852104436873, 4940.530649588283, 4897.461279259279, 4919.016196760662, 4926.630502712275, 4927.0, 4931.358923339979, 5144.871378215537, 5160.071187186305, 5207.765650545734, 5236.9738151309, 5237.528622423962, 5812.157237200625, 5821.068629019336, 5822.359540450547, 5828.854278643068, 5868.325142608612, 5655.5475561954945, 5838.299404071496, 5840.182374467578, 5858.091995740624, 5872.853178670567, 5368.194770784375, 5452.318460192476, 5473.0969390050595, 5479.506844383985, 5519.420460851593, 5915.911261331084, 5948.137520059568, 6009.0, 6015.849603759941, 6023.45788879003, 5646.774129034875, 5776.306843178845, 5785.479306862402, 5823.126531020381, 5823.2429784128635, 5792.218050563204, 5911.852203694942, 5912.054071348563, 5914.201582786269, 5967.940320596738, 5691.715414229272, 5801.796937107181, 5807.0, 5808.215890854765, 5813.912791308134, 5610.0, 5716.228309178291, 5721.370649228582, 5738.799142030009, 5752.482276595127, 4452.510223875371, 4502.279635258343, 4507.323901414748, 4511.526289739596, 4540.72755634663, 4013.1973605279063, 4019.9397009044887, 4028.2144981728284, 4053.067794407244, 4072.1041370898947, 5341.439148889311, 5396.325459317585, 5539.9169012464845, 5592.8042518512475, 5607.271054762855, 5836.124581312837, 5908.734106965197, 5917.911231331534, 5934.258217722785, 5935.1987481690285, 5467.781288748405, 5490.780368785204, 5491.533219676372, 5493.7802487900835, 5511.641743286754, 4982.427020892644, 5061.746912654353, 5106.336176297058, 5115.283860259567, 5153.484651534892, 6042.8791424170395, 6046.879062418727, 6048.0625503047095, 6052.727627256784, 6097.939020609826, 5795.94204057954, 5802.100674395476, 5807.796727114531, 5812.82561523155, 5826.7086645666695, 5864.706764661835, 5989.670568118795, 6007.12896629995, 6010.368911264254, 6098.288897731724, 5736.254286942838, 5850.707464626837, 5859.091840764689, 5880.852978675567, 5890.852728681817, 5874.236349274567, 5906.881862362643, 5958.0, 5964.194833697396, 5965.045592705146, 5571.415001424792, 5641.041023026178, 5705.115707065412, 5709.54323654115, 5776.046952252999, 5736.340320863154, 5812.476877081083, 5825.330087039875, 5840.883182336414, 5843.094320380348, 5472.097103977799, 5717.599768016199, 5718.99917514425, 5719.914201286901, 5725.971370143123, 5695.943040569541, 5710.37185909558, 5773.04744717117, 5790.652560846363, 5840.795572154954, 5669.6314739541795, 5677.574181936454, 5678.943210567842, 5683.3748287687495, 5740.454656807597], "unit": "ms", "name": "dojo.html", "value": 5390.367546547357}, {"lowerIsBetter": false, "replicates": [574.7902015764246, 578.4649199490473, 586.8562202260447, 590.4449817171858, 606.9180660610816, 30798.69201307986, 38804.0, 39591.40612890809, 39600.40599391011, 39678.80160599197, 42487.575124248746, 42763.14473710524, 42815.3577696334, 42873.78563107188, 42984.570154298526, 7427.628618569077, 7606.04924384452, 8573.828523429527, 8752.956235218815, 8758.430702004382, 10161.745956351077, 10495.055445009948, 10530.736731581695, 10537.947310263438, 10570.577176912915, 10266.0, 10351.378917264989, 10353.534090965924, 10367.06696397324, 10375.0, 7626.961865190694, 7862.724804631867, 10359.533820978037, 10398.948005259925, 10430.947845260802, 10120.595176192943, 10136.847947280796, 10144.239182061301, 10150.492475376202, 10260.076593106656, 38449.42325865114, 38534.61465385338, 38742.61257387418, 38921.41617875734, 38950.61049389498, 3227.757918156124, 3238.627557830856, 3293.901182964515, 4022.2960981828064, 4102.220578090153, 29900.85049574738, 29995.100146995625, 30140.0957971259, 30203.935492533412, 30308.090757277318, 3831.885043448701, 3903.1413089120347, 3976.80115994202, 3978.5026871641044, 4081.8367265309353, 258662.0, 260052.0, 260146.0, 261425.0, 261993.0, 56023.0, 56249.0, 56338.154927676114, 56404.71797641068, 56432.15351769727, 58729.4127058724, 59555.404445954984, 65804.34195657982, 66600.00599946028, 66773.66613167, 13957.720845583031, 13990.510332138327, 14230.644233894029, 14237.786433203302, 14255.643608909653, 10679.626213082505, 10763.677089687322, 10801.945990269998, 10802.567897284178, 10809.945950270197, 18898.716519252222, 18918.81081189198, 19032.143553540125, 19048.71426928597, 19083.236670533024, 7422.554646721214, 7605.771826845204, 7627.656755446018, 7703.0, 7759.379249660014, 925.708401853414, 945.7304668169538, 970.2335155227432, 992.1517102876936, 1006.6376104602338, 27342.953650590516, 27476.45047099087, 27849.860750696116, 27868.860655696997, 27977.30056748557, 984.8719666443317, 1016.5679586175846, 1023.3399457349967, 1032.932859364139, 1157.1263695909533, 14595.416183352547, 14692.779608305884, 14801.259937003322, 14807.925960370128, 15651.373945042298, 240862.0, 243635.0, 243693.0, 244038.0, 244103.0, 48481.5151848484, 48573.51426485761, 48573.757131214115, 48741.51258487441, 48755.0, 1301.4273719563362, 1308.1562392256942, 1319.874611911886, 1323.993380033113, 1329.1227789658778, 28318.716812832434, 28880.2779930495, 28894.277643059093, 29352.70647293585, 29439.26401839971, 44898.77550612226, 45355.31967020563, 45365.0, 45413.0, 45483.54516454926, 281746.0, 285795.0, 286007.0, 286022.0, 287654.0, 27759.861200693867, 31227.219319516284, 31468.842655786575, 31484.370312594077, 31752.0, 37140.257194856495, 38245.23509529849, 38610.42084368681, 38837.6116238834, 39191.2161756769, 34738.65261347455, 35338.64661353456, 35415.82292088523, 35756.0, 36232.456513151796, 28408.57387139236, 33169.50245746267, 34260.0, 35743.285134296646, 36051.63948360483, 32109.357812843144, 32256.354872901942, 32338.35323293568, 32433.35133297368, 35745.28509429849, 28426.857865711365, 29573.852130739207, 30314.696853031186, 30658.540121897742, 30837.537436938914, 25376.619350710123, 25877.0, 26898.462030758885, 27056.45887082208, 27168.320791980357], "unit": "ms", "name": "ext.html", "value": 18227.156667568575}, {"lowerIsBetter": false, "replicates": [354.71977138060936, 355.61060638600725, 356.7681007345226, 356.7805799433349, 357.7513628028518, 14331.570052898403, 14475.493357732455, 16420.58948526286, 16684.249208785604, 17389.304427822903, 15622.0, 16827.831721682778, 16899.070551119683, 16908.83091169088, 17644.735328970044, 5312.840614781563, 5466.781328746865, 5468.808591699291, 5473.480019398151, 5480.0, 3999.540052893911, 4131.607497287753, 5405.270288511059, 5422.701751403672, 5803.274590676166, 5831.329397119322, 5862.560307976897, 5889.9705501472645, 5901.586888917777, 5917.260342457193, 5725.227094342273, 5860.09168578871, 5868.471837534642, 5868.647881127146, 5883.88232235355, 5178.715170665612, 5185.299984502081, 5201.55786758126, 5204.9479505204845, 5216.061109000379, 18621.7206741899, 19291.0, 20448.0, 21329.893350533304, 21379.572408551743, 2150.064721846002, 2150.516133869882, 2192.353255789546, 2306.3196357074703, 2368.171140100968, 9057.0, 9220.308476864195, 9695.175910047654, 10125.797484050277, 12948.546800861925, 1829.4694538583794, 1834.660587791263, 1894.9242030318908, 1902.5624106455455, 1945.7470528831302, 59993.0, 62234.066489003155, 62731.37268627301, 63080.36919630791, 63338.0, 9566.521673916277, 9620.615175393046, 9623.133917947418, 9646.807063858683, 9695.224382049419, 12562.937185314198, 12588.37058147089, 12601.18092323996, 12602.55891043809, 12609.684757880943, 6994.090768200102, 7203.351698347069, 7246.202917679051, 7249.673764680602, 7258.165310989304, 6930.688119034656, 6933.68798404073, 6991.790246292518, 7041.3310735480045, 7117.4306055515435, 4066.4510291110914, 4070.3487442009728, 4075.8777236682945, 4224.155168966219, 4229.7250678706405, 1566.749320108777, 1598.5044636162827, 1614.564067701714, 1616.5392863034215, 1693.3903794633923, 843.9451435656663, 886.4902680958475, 904.5703290936884, 941.6845356805546, 974.5370948799264, 15594.376224950875, 16587.336506539843, 16786.4124755633, 16827.41104061352, 17103.48689539316, 937.7702462896634, 949.2595775295281, 974.0989584634326, 994.3288280410839, 1003.9397636141855, 9184.371499102795, 9545.474998875128, 9596.0, 9621.470819104876, 9722.951385243028, 472049.0, 475017.0, 476530.0, 485165.0, 485983.0, 75061.62469187619, 75106.62446687731, 75787.0, 76054.0, 76215.0, 1102.6251074634663, 1107.7839821234782, 1144.1361771862357, 1158.4323681396056, 1158.6408213453856, 14761.409543618134, 14829.703405932038, 14930.178840163895, 15040.699186016438, 15207.239638018276, 32800.83599581987, 33169.50245746364, 33287.66712332846, 36055.0, 36775.632243677224, 41978.790106050496, 47311.52688473071, 47946.280805788636, 48695.51304487052, 50532.0, 9109.498977556297, 9717.416954982724, 9876.950615246878, 9879.703608891745, 9924.354916930377, 11077.113830893348, 11135.554577816796, 11187.496562654538, 11192.776144477228, 11197.496112675111, 13520.797188042385, 14071.577852664437, 14164.78752818688, 14432.206228657524, 14477.855221447651, 13924.651883702583, 14032.649183770487, 14281.42874285017, 14445.06107103035, 14505.129692218501, 14367.64080897944, 14487.20320382389, 14680.706385872436, 14994.500549944918, 15028.474003409829, 9030.36787424874, 9106.544672766468, 9183.357164998386, 9261.629534818532, 9290.070992900926, 9875.950620246853, 9887.802243955224, 10217.0, 14558.781618275947, 14710.55868323952], "unit": "ms", "name": "jquery.html", "value": 8628.186700953138}, {"lowerIsBetter": false, "replicates": [1298.5130576033987, 1423.3452611798573, 1485.2990285745616, 1606.389571962654, 1718.1151706870955, 6634.635095069769, 7257.7096916123355, 7542.170361260257, 7549.0186275784135, 7549.471536992412, 7560.621968901561, 7579.052618422697, 7583.2795884391035, 7627.694892204319, 7634.732784352549, 1954.6188493243824, 1992.7608686957585, 2270.375646697163, 2271.806896413807, 2293.9541209175804, 2244.1135751378197, 2295.494991101963, 2297.3337732057767, 2298.138198175684, 2298.276043046435, 2223.3885681437573, 2259.7740225977436, 2260.367097212783, 2262.4117729390314, 2263.54729054189, 1958.177565422526, 2000.2299114840746, 2003.268806885491, 2216.1246307708525, 2248.1457046322457, 2250.696156018933, 2259.096361455431, 2259.954800903989, 2260.672202530624, 2261.366817291161, 7556.168821429638, 7700.383969282444, 7700.614969251515, 7703.998480197594, 7709.653065612061, 4607.470140933769, 4679.485256621769, 4711.599514041311, 4714.387129673155, 4716.434027916637, 3117.9064628061196, 3147.5278708193723, 3238.6559577775197, 3247.025892232321, 3268.297316077031, 1003.3929472669004, 1011.0294117646994, 1015.5531566110888, 1023.2223510132278, 1034.5137785240925, 10577.36535807854, 10602.893971060268, 10611.098056665192, 10613.681589552249, 10617.46912654372, 4138.461999939988, 4214.726042807269, 4261.4673165854265, 4278.9730464688255, 4282.036541778138, 9111.225545828545, 9130.589123489459, 9144.222741066951, 9144.63421463134, 9146.54267286633, 4756.762161891892, 4758.1673207188605, 4777.665563410597, 4778.1399348117675, 4779.95220047802, 4337.436133302714, 4344.56554344563, 4349.151915376535, 4351.412559304516, 4353.869383918487, 3671.7796932184156, 3685.391910334765, 3690.9630903691154, 3709.3323201823932, 3712.2204337088974, 1156.4795841871191, 1165.4930105404007, 1171.8300846377228, 1173.4895320535595, 1180.167981572989, 1079.8974097460728, 1291.8256035435095, 1299.1685321394316, 1301.707115898934, 1337.2778699502355, 7357.337839594462, 7381.483296169216, 7394.704211831467, 7410.555366678017, 7421.406287496988, 1086.2667699303097, 1115.8437818705388, 1115.8605174353206, 1129.5538262386312, 1140.7091191746051, 5366.973165134149, 5501.229827824108, 5919.437653422918, 6084.908726369108, 6096.512279017757, 8919.06349833271, 9035.322350823582, 9075.13786190311, 9431.434113953184, 9544.904550954541, 9421.62313507452, 9426.387284826464, 9432.0, 9438.622455101719, 9442.622295108256, 7206.171290301578, 7212.567245965259, 7357.374623156985, 7378.188399276075, 7389.963050184822, 7340.265973402617, 7358.96320518394, 7364.447666425147, 7373.520721153108, 7376.7786966391095, 8061.476004059952, 8252.793680158044, 8332.375071869756, 8446.493210407396, 8463.111373305714, 8470.830583388175, 8522.957385213034, 8665.566721663765, 8666.78333041679, 8680.088590697886, 6713.160854893138, 6718.899216511659, 6816.454683625199, 6831.726730930707, 6838.281980392187, 7526.059242594676, 7564.016677831846, 7566.394688424803, 7580.279873412017, 7585.393168546613, 7056.518131192302, 7157.284271572801, 7186.496945213785, 7263.745768898063, 7287.817804554928, 6994.265602111908, 7085.000198043299, 7114.288571142844, 7169.856602867809, 7220.711171553079, 7166.749163779243, 7194.820129496805, 7249.565026098451, 7257.637118144177, 7267.745628902963, 6587.901181482186, 6642.800715978528, 6645.534812563074, 6647.368499992493, 6652.73389064432, 6533.771318003847, 6603.339666033358, 6657.101291325609, 6665.0, 6668.533202675766], "unit": "ms", "name": "mootools.html", "value": 4496.272242838349}, {"lowerIsBetter": false, "replicates": [317.47299482858455, 321.47278463320157, 324.6347858659009, 324.8765469121734, 326.95749552558163, 5997.52019838413, 6039.1243269725865, 6044.667543285118, 6075.605085669434, 6079.3008803987495, 5962.135490353896, 5966.343702192756, 5968.253968253968, 5969.044952807551, 5969.492593129578, 4324.82700691972, 4420.602145806877, 4436.800343984529, 4567.862964111082, 4581.244094724383, 4550.476695180064, 4551.249043907767, 4584.862454126365, 4598.425196850394, 4598.839040633578, 4539.432570928634, 4544.863654090383, 4551.226291530448, 4552.476465206494, 4553.066621342633, 4326.6106050455455, 4421.624161946238, 4435.090806384684, 4535.342375355555, 4555.931661025054, 3970.642642162219, 3972.967028572563, 3981.9845939285296, 3984.0, 3987.880363589068, 5707.086866101446, 5711.7715291388295, 5714.828460165656, 5716.657000579937, 5729.598928075036, 5215.478452154754, 5313.096773548467, 5324.840254792362, 5329.22726204702, 5334.706591137486, 6361.950278204091, 6365.236171659384, 6367.204099487564, 6378.0, 6379.36206379363, 4366.672499562545, 4377.233984052778, 4379.824807007716, 4419.049904270597, 4430.335449682573, 6624.73501059957, 6627.73489060437, 6634.402903738638, 6637.103990961206, 6643.93356066438, 4430.069685366109, 4488.506264310923, 4521.751303678264, 4527.637788976874, 4535.614472769786, 4551.271796512542, 4560.840370587013, 4567.794449249792, 4589.747563884019, 4596.0, 5274.367075950834, 5305.36335639718, 5306.6550674206055, 5307.893842123136, 5310.893782124336, 6398.744050237939, 6400.743970241138, 6402.775902843378, 6406.967965160145, 6412.871742565123, 1538.7845701601784, 1570.3169121432213, 1573.590866374728, 1578.0610536730626, 1580.0677600215927, 1027.091024443358, 1034.0280136671504, 1034.5137785240925, 1045.6601604478574, 1054.4147997861107, 6099.237595300588, 6119.5410344224365, 6134.202553667995, 6156.630602163885, 6165.660888651078, 6551.1155993941165, 6554.770583029666, 6557.409833115043, 6558.901616475757, 6559.114519539801, 6020.518358531307, 6145.231846019247, 6148.508119350441, 6157.599756015845, 6160.753569857246, 3538.0093573799377, 3538.8230588470988, 3561.074120728629, 3561.71506279497, 3564.8574057037945, 6770.32296770319, 6778.796636100925, 6782.491313151533, 6784.660766961632, 6794.864102717918, 6693.866122677519, 6704.296048914891, 6704.731810727614, 6708.262091169968, 6708.865822683519, 6699.698513566901, 6872.175338959257, 6945.548539344926, 6951.965240173767, 6953.061336719478, 6665.20017597872, 6669.633170175687, 6674.8998765017595, 6676.532642714963, 6688.598684078971, 6461.515386345949, 6625.734970601122, 6627.072209890715, 6634.568753031038, 6636.701348439428, 6931.480138989697, 6936.583804971718, 6944.965275173592, 6945.027696122446, 6952.5133240674695, 4404.339349097661, 4418.0, 4420.0, 4421.182081314967, 4428.977855110704, 6667.299933506912, 6667.533272670867, 6672.966635166793, 6678.966605166943, 6680.966595166993, 6304.698784943371, 6476.255230648348, 6479.8056058318325, 6485.77299794505, 6493.318201588954, 6453.386928241809, 6453.806385808433, 6459.031145328238, 6468.223813142453, 6472.223333200046, 6203.131561581473, 6340.207474065742, 6344.333844946399, 6364.204474440695, 6367.108604795239, 6500.512461565307, 6504.349565043458, 6508.8047358579315, 6511.218653761579, 6512.772052978123, 6306.621602703853, 6340.144080549251, 6345.746170153142, 6348.9047664286, 6355.809325720235], "unit": "ms", "name": "prototype.html", "value": 4798.827329707531}, {"lowerIsBetter": false, "replicates": [744.4305106593457, 765.5483264873724, 778.1440415542907, 814.4217605500095, 861.4278940932797, 14014.649633759149, 14168.57494275172, 14233.074850134733, 14244.572662820106, 14359.712805743879, 14323.498677546288, 14729.631759206026, 14734.852651473482, 14737.48418805342, 14741.41034358627, 678.4097834883659, 696.1611258433576, 814.2834305810893, 828.7596596986866, 830.3647709502205, 803.2529747334976, 828.1759649149105, 833.62903507939, 833.6790335720731, 833.737372727592, 856.4903882190087, 857.4983634573782, 861.5347712235385, 863.3179787967498, 864.0754392799707, 694.920084190317, 714.7784186902077, 715.4455297144729, 716.0333549707905, 856.1139220906363, 605.8121982185537, 614.1463365921372, 619.6313193649771, 620.1411045701691, 621.2203684376125, 13867.37596808146, 13993.650158746113, 13998.370073346723, 14031.508897188549, 14037.438502459889, 12443.315617641027, 12621.116521843474, 12772.297523636196, 13030.0, 13395.397207125703, 10028.197744180448, 10433.686989390331, 10493.802716508902, 10639.734006649818, 10683.35899846004, 3649.0613714820265, 3699.85200591976, 3714.739968202227, 3715.0526615713093, 3839.4816699745456, 47677.28484072707, 47857.282140767915, 48135.277970830466, 48384.0, 48407.0, 10291.485425728684, 10460.476976151162, 10517.579296828042, 10612.787744245226, 10622.521986510626, 16652.916735416246, 16695.24871380791, 16705.916470417815, 16852.410165644145, 16873.662526749395, 7803.141654418009, 7931.127575966639, 7983.960080199678, 8036.357091432671, 8108.797280068045, 4365.388845561624, 4432.556744325606, 4458.7547684877645, 4466.307722303049, 4482.7758612069265, 2954.1285320830407, 2974.866131024109, 2977.865996030184, 2989.043506078034, 2996.6553846307515, 533.557147567518, 534.4922323792335, 547.7261369315343, 551.456815037188, 556.5575367582782, 547.9808206712746, 593.9435753603401, 636.2841802971657, 690.5442408010689, 715.4348065028673, 8143.389245806469, 8316.0, 8386.706465273686, 8469.915300846913, 8525.887677186605, 601.6871226961955, 662.3244290823333, 671.771597656799, 683.3576438148125, 728.661172554754, 4024.0, 4081.8163182656854, 4191.622753952098, 4231.597998190167, 4287.699861009762, 57222.42777572254, 57519.424805751405, 58030.41969580334, 58186.418135818945, 58280.0, 51627.22559161616, 51650.74174629103, 51804.22293665598, 51808.48191518112, 52543.73728131335, 46896.0, 47439.28841067387, 47466.525334746904, 48144.277835832494, 48181.27728084082, 8522.829543409222, 8651.394402391772, 8812.867806982773, 9113.134252246025, 9178.173964343241, 35090.649093508735, 35463.82268088746, 35535.28929421346, 36172.638273616925, 36995.63004369922, 44810.0, 45648.31527527023, 45803.54196458126, 45993.31010034919, 46532.30201547047, 11091.500882460146, 11205.43972801373, 11242.0, 11254.831177532507, 11360.54557817678, 11754.29474231549, 11838.526458941546, 11874.76250475003, 12898.548550800677, 13018.739625207632, 11598.18812683104, 11875.52497900109, 12080.637580872588, 12221.938890305491, 12453.25280483174, 11726.355050472306, 12119.394030298627, 12135.0, 12238.877611223774, 12464.813027804408, 7925.642427556041, 10085.0, 10765.300255483682, 11697.23967942081, 11966.341851198267, 10213.642522511973, 10308.690739277834, 10477.37135771856, 10478.161747060065, 10641.095506882002, 9749.268804839807, 9955.303128780915, 10044.196464282986, 10114.747131321776, 10118.898811011795], "unit": "ms", "name": "yui.html", "value": 6383.773406657499}], "name": "dromaeo_css", "value": 6991.330915077984}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 105.0, 110.0, 110.0, 110.0, 99.0, 109.0, 111.0, 103.0], "unit": "ms", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [158.0, 124.0, 148.0, 162.0, 125.0, 158.0, 157.0, 125.0, 170.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.3}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 160.0, 160.0, 161.0, 160.0, 162.0, 163.0, 164.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 161.6}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 89.0, 101.0, 71.0, 70.0, 70.0, 93.0, 98.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 80.4}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 134.0, 93.0, 94.0, 94.0, 141.0, 92.0, 92.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.9}, {"lowerIsBetter": true, "replicates": [111.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 110.0, 109.0, 111.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 57.0, 58.0, 59.0, 60.0, 59.0, 58.0, 60.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 58.2}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [82.0, 83.0, 86.0, 83.0, 83.0, 84.0, 85.0, 86.0, 88.0, 87.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.7}, {"lowerIsBetter": true, "replicates": [126.0, 122.0, 116.0, 114.0, 118.0, 116.0, 121.0, 128.0, 118.0, 122.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.1}, {"lowerIsBetter": true, "replicates": [143.0, 152.0, 150.0, 148.0, 161.0, 140.0, 143.0, 161.0, 149.0, 146.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.3}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 64.0, 67.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.4}], "name": "kraken", "value": 1497.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [189.07165815844206], "unit": "score", "name": "Richards", "value": 186.71227800000003}, {"lowerIsBetter": false, "replicates": [194.89378288832586], "unit": "score", "name": "DeltaBlue", "value": 339.25145799999996}, {"lowerIsBetter": false, "replicates": [285.143997718848], "unit": "score", "name": "Encrypt", "value": 933.4967669999996}, {"lowerIsBetter": false, "replicates": [5005.0], "unit": "score", "name": "Decrypt", "value": 53.18301698301701}, {"lowerIsBetter": false, "replicates": [1958.9041095890411], "unit": "score", "name": "RayTrace", "value": 377.7566223776223}, {"lowerIsBetter": false, "replicates": [775.3679318357862], "unit": "score", "name": "Earley", "value": 859.5441888111892}, {"lowerIsBetter": false, "replicates": [11916.666666666666], "unit": "score", "name": "Boyer", "value": 55.926965034964994}, {"lowerIsBetter": false, "replicates": [25375.0], "unit": "score", "name": "RegExp", "value": 35.90088669950742}, {"lowerIsBetter": false, "replicates": [649.4512588766946], "unit": "score", "name": "Splay", "value": 125.47669880715702}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 19557.049208037934}]} 18:50:39 INFO - RETURN: dromaeo_css: 6991.33 18:50:39 INFO - RETURN: kraken: 1497.30 18:50:39 INFO - RETURN: v8_7: 19557.05 18:50:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "19557.05"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1497.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6991.33"}}} 18:50:39 INFO - Return code: 0 18:50:39 INFO - # TBPL SUCCESS # 18:50:39 INFO - Running post-action listener: _resource_record_post_action 18:50:39 INFO - Running post-run listener: _resource_record_post_run 18:50:40 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 0; Write bytes: 510914560; Read time: 0; Write time: 515572 18:50:40 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 237703168; Read time: 0; Write time: 329640 18:50:40 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 0; Write bytes: 273211392; Read time: 0; Write time: 185932 18:50:40 INFO - Running post-run listener: _upload_blobber_files 18:50:40 INFO - Blob upload gear active. 18:50:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:50:40 INFO - Copying logs to upload dir... 18:50:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.425739 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-11-23 18:50:39.603948) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:39.607570) ========= 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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010229 build_url: 'https://queue.taskcluster.net/v1/task/gsq_AB6xRPyDXFFoP9xoEg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:39.659733) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:39.660163) ========= 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=004b26eee9c3b360c8654f40000001e1-1448331679.866863-36218787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:39.710372) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:50:39.710736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:50:39.711134) ========= ========= Total master_lag: 0.22 =========