builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1448452837.56 results: success (0) buildid: 20151125023019 builduid: 87ce33da4b244752a3039b6825baf39d revision: 42e781cabc64ee811fad3962ecad5a8cb5a7cacc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.562133) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.562656) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.562943) ========= 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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006672 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.585097) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.585401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.629802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.630068) ========= 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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004624 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.694736) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.695007) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.695359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:37.695625) ========= 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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 04:00:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2015-11-25 04:00:38 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.381978 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:38.090191) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:38.090502) ========= 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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018598 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:38.139910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-25 04:00:38.140266) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 42e781cabc64ee811fad3962ecad5a8cb5a7cacc --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 42e781cabc64ee811fad3962ecad5a8cb5a7cacc --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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 04:00:38,460 truncating revision to first 12 chars 2015-11-25 04:00:38,460 Setting DEBUG logging. 2015-11-25 04:00:38,460 attempt 1/10 2015-11-25 04:00:38,460 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/42e781cabc64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 04:00:39,254 unpacking tar archive at: b2g-inbound-42e781cabc64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.092539 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-25 04:00:39.245035) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:39.245331) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:39.257372) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:39.257641) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 04:00:39.258041) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2015-11-25 04:00:39.258333) ========= /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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:00:39 INFO - MultiFileLogger online at 20151125 04:00:39 in /builds/slave/test-pgo 04:00:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 04:00:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 04:00:39 INFO - {'append_to_log': False, 04:00:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 04:00:39 INFO - 'blob_upload_branch': 'B2G-Inbound', 04:00:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 04:00:39 INFO - 'branch': 'B2G-Inbound', 04:00:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:00:39 INFO - 'config_files': ('talos/linux_config.py',), 04:00:39 INFO - 'default_actions': ('clobber', 04:00:39 INFO - 'read-buildbot-config', 04:00:39 INFO - 'download-and-extract', 04:00:39 INFO - 'populate-webroot', 04:00:39 INFO - 'create-virtualenv', 04:00:39 INFO - 'install', 04:00:39 INFO - 'run-tests'), 04:00:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:00:39 INFO - 'download_minidump_stackwalk': True, 04:00:39 INFO - 'download_symbols': 'ondemand', 04:00:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:00:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:00:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:00:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:00:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:00:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:00:39 INFO - 'installer_path': 'installer.exe', 04:00:39 INFO - 'log_level': 'info', 04:00:39 INFO - 'log_name': 'talos', 04:00:39 INFO - 'log_to_console': True, 04:00:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:00:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:00:39 INFO - 'opt_config_files': (), 04:00:39 INFO - 'pip_index': False, 04:00:39 INFO - 'sps_profile': False, 04:00:39 INFO - 'sps_profile_interval': 0, 04:00:39 INFO - 'suite': 'dromaeojs', 04:00:39 INFO - 'system_bits': '64', 04:00:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:00:39 INFO - 'title': 'talos-linux64-ix-042', 04:00:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:00:39 INFO - 'use_talos_json': True, 04:00:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 04:00:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:00:39 INFO - 'work_dir': 'build'} 04:00:39 INFO - ##### 04:00:39 INFO - ##### Running clobber step. 04:00:39 INFO - ##### 04:00:39 INFO - Running pre-action listener: _resource_record_pre_action 04:00:39 INFO - Running main action method: clobber 04:00:39 INFO - rmtree: /builds/slave/test-pgo/build 04:00:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 04:00:40 INFO - Running post-action listener: _resource_record_post_action 04:00:40 INFO - ##### 04:00:40 INFO - ##### Running read-buildbot-config step. 04:00:40 INFO - ##### 04:00:40 INFO - Running pre-action listener: _resource_record_pre_action 04:00:40 INFO - Running main action method: read_buildbot_config 04:00:40 INFO - Using buildbot properties: 04:00:40 INFO - { 04:00:40 INFO - "properties": { 04:00:40 INFO - "buildnumber": 1005, 04:00:40 INFO - "product": "firefox", 04:00:40 INFO - "script_repo_revision": "production", 04:00:40 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 04:00:40 INFO - "repository": "", 04:00:40 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 04:00:40 INFO - "buildid": "20151125023019", 04:00:40 INFO - "slavename": "talos-linux64-ix-042", 04:00:40 INFO - "pgo_build": "True", 04:00:40 INFO - "basedir": "/builds/slave/test-pgo", 04:00:40 INFO - "project": "", 04:00:40 INFO - "platform": "ubuntu64_hw", 04:00:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:00:40 INFO - "slavebuilddir": "test", 04:00:40 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 04:00:40 INFO - "branch": "b2g-inbound", 04:00:40 INFO - "repo_path": "integration/b2g-inbound", 04:00:40 INFO - "stage_platform": "linux64-pgo", 04:00:40 INFO - "builduid": "87ce33da4b244752a3039b6825baf39d", 04:00:40 INFO - "revision": "42e781cabc64ee811fad3962ecad5a8cb5a7cacc" 04:00:40 INFO - }, 04:00:40 INFO - "sourcestamp": { 04:00:40 INFO - "repository": "", 04:00:40 INFO - "hasPatch": false, 04:00:40 INFO - "project": "", 04:00:40 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 04:00:40 INFO - "changes": [ 04:00:40 INFO - { 04:00:40 INFO - "category": null, 04:00:40 INFO - "files": [ 04:00:40 INFO - { 04:00:40 INFO - "url": null, 04:00:40 INFO - "name": "https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 04:00:40 INFO - } 04:00:40 INFO - ], 04:00:40 INFO - "repository": "", 04:00:40 INFO - "rev": "42e781cabc64ee811fad3962ecad5a8cb5a7cacc", 04:00:40 INFO - "who": "sendchange", 04:00:40 INFO - "when": 1448452822, 04:00:40 INFO - "number": 6705224, 04:00:40 INFO - "comments": "", 04:00:40 INFO - "project": "", 04:00:40 INFO - "at": "Wed 25 Nov 2015 04:00:22", 04:00:40 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 04:00:40 INFO - "revlink": "", 04:00:40 INFO - "properties": [ 04:00:40 INFO - [ 04:00:40 INFO - "buildid", 04:00:40 INFO - "20151125023019", 04:00:40 INFO - "Change" 04:00:40 INFO - ], 04:00:40 INFO - [ 04:00:40 INFO - "builduid", 04:00:40 INFO - "87ce33da4b244752a3039b6825baf39d", 04:00:40 INFO - "Change" 04:00:40 INFO - ], 04:00:40 INFO - [ 04:00:40 INFO - "pgo_build", 04:00:40 INFO - "True", 04:00:40 INFO - "Change" 04:00:40 INFO - ] 04:00:40 INFO - ], 04:00:40 INFO - "revision": "42e781cabc64ee811fad3962ecad5a8cb5a7cacc" 04:00:40 INFO - } 04:00:40 INFO - ], 04:00:40 INFO - "revision": "42e781cabc64ee811fad3962ecad5a8cb5a7cacc" 04:00:40 INFO - } 04:00:40 INFO - } 04:00:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 04:00:40 INFO - Running post-action listener: _resource_record_post_action 04:00:40 INFO - ##### 04:00:40 INFO - ##### Running download-and-extract step. 04:00:40 INFO - ##### 04:00:40 INFO - Running pre-action listener: _resource_record_pre_action 04:00:40 INFO - Running main action method: download_and_extract 04:00:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 04:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:40 INFO - https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:00:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/test_packages.json 04:00:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/test_packages.json 04:00:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 04:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 04:00:42 INFO - Downloaded 1302 bytes. 04:00:42 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 04:00:42 INFO - Using the following test package requirements: 04:00:42 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:42 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:00:42 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'jsshell-linux-x86_64.zip'], 04:00:42 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:00:42 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:42 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:00:42 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:00:42 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:00:42 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:42 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:42 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:00:42 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:42 INFO - https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:43 INFO - Downloaded 22530082 bytes. 04:00:43 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 04:00:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:00:43 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 04:00:44 INFO - Return code: 0 04:00:44 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 04:00: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')]} 04:00:44 INFO - https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:46 INFO - Downloaded 22530082 bytes. 04:00: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 04:00:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 04:00: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 04:00:47 INFO - Return code: 0 04:00: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')]} 04:00:47 INFO - https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:48 INFO - Downloaded 11131717 bytes. 04:00:48 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 04:00:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 04:00:48 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 04:00:48 INFO - Return code: 0 04:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:48 INFO - https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:00:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:00:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:00:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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 04:00:51 INFO - Downloaded 58387660 bytes. 04:00:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:00:51 INFO - mkdir: /builds/slave/test-pgo/properties 04:00:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 04:00:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 04:00:51 INFO - Contents: 04:00:51 INFO - build_url:https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 04:00:51 INFO - Running post-action listener: _resource_record_post_action 04:00:51 INFO - Running post-action listener: set_extra_try_arguments 04:00:51 INFO - ##### 04:00:51 INFO - ##### Running populate-webroot step. 04:00:51 INFO - ##### 04:00:51 INFO - Running pre-action listener: _resource_record_pre_action 04:00:51 INFO - Running main action method: populate_webroot 04:00:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:00:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:00:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 04:00:51 INFO - '../fennec_ids.txt'], 04:00:51 INFO - 'tests': ['tcheck2']}, 04:00:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:00:51 INFO - '--tppagecycles', 04:00:51 INFO - '7'], 04:00:51 INFO - 'tests': ['tsvgm']}}, 04:00:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:00:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:00:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:00:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:00:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'tests': ['damp', 'tps']}, 04:00:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['damp', 'tps']}, 04:00:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:00:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:00:51 INFO - 'other': {'tests': ['a11yr', 04:00:51 INFO - 'ts_paint', 04:00:51 INFO - 'tpaint', 04:00:51 INFO - 'sessionrestore', 04:00:51 INFO - 'sessionrestore_no_auto_restore']}, 04:00:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['a11yr', 04:00:51 INFO - 'ts_paint', 04:00:51 INFO - 'tpaint', 04:00:51 INFO - 'sessionrestore', 04:00:51 INFO - 'sessionrestore_no_auto_restore']}, 04:00:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['a11yr', 04:00:51 INFO - 'ts_paint', 04:00:51 INFO - 'tpaint', 04:00:51 INFO - 'sessionrestore', 04:00:51 INFO - 'sessionrestore_no_auto_restore']}, 04:00:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['a11yr', 04:00:51 INFO - 'ts_paint', 04:00:51 INFO - 'tpaint', 04:00:51 INFO - 'sessionrestore', 04:00:51 INFO - 'sessionrestore_no_auto_restore']}, 04:00:51 INFO - 'other_l64': {'tests': ['a11yr', 04:00:51 INFO - 'ts_paint', 04:00:51 INFO - 'tpaint', 04:00:51 INFO - 'sessionrestore', 04:00:51 INFO - 'sessionrestore_no_auto_restore']}, 04:00:51 INFO - 'other_nol64': {'tests': ['a11yr', 04:00:51 INFO - 'ts_paint', 04:00:51 INFO - 'tpaint', 04:00:51 INFO - 'sessionrestore', 04:00:51 INFO - 'sessionrestore_no_auto_restore']}, 04:00:51 INFO - 'svgr': {'tests': ['tsvgx', 04:00:51 INFO - 'tsvgr_opacity', 04:00:51 INFO - 'tart', 04:00:51 INFO - 'tscrollx', 04:00:51 INFO - 'cart']}, 04:00:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['tsvgx', 04:00:51 INFO - 'tsvgr_opacity', 04:00:51 INFO - 'tart', 04:00:51 INFO - 'tscrollx', 04:00:51 INFO - 'cart']}, 04:00:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'tests': ['tp5o']}, 04:00:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'talos_options': ['--e10s'], 04:00:51 INFO - 'tests': ['tp5o']}, 04:00:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'talos_options': ['--xperf_path', 04:00:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:51 INFO - 'tests': ['tp5n']}, 04:00:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:51 INFO - 'talos_options': ['--e10s', 04:00:51 INFO - '--xperf_path', 04:00:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:51 INFO - 'tests': ['tp5n']}}, 04:00:51 INFO - 'talos.zip': {'path': '', 04:00:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:00:51 INFO - Running post-action listener: _resource_record_post_action 04:00:51 INFO - ##### 04:00:51 INFO - ##### Running create-virtualenv step. 04:00:51 INFO - ##### 04:00:51 INFO - Running pre-action listener: _resource_record_pre_action 04:00:51 INFO - Running main action method: create_virtualenv 04:00:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 04:00:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 04:00:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 04:00:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:00:51 INFO - Using real prefix '/usr' 04:00:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 04:00:51 INFO - Installing distribute.............................................................................................................................................................................................done. 04:00:53 INFO - Installing pip.................done. 04:00:53 INFO - Return code: 0 04:00:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 04:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:00:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 04:00:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:00:53 INFO - Using env: {'DISPLAY': ':0', 04:00:53 INFO - 'HOME': '/home/cltbld', 04:00:53 INFO - 'LANG': 'en_US.UTF-8', 04:00:53 INFO - 'LANGUAGE': 'en_US:en', 04:00:53 INFO - 'LOGNAME': 'cltbld', 04:00:53 INFO - 'MAIL': '/var/mail/cltbld', 04:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:53 INFO - 'MOZ_NO_REMOTE': '1', 04:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:53 INFO - 'NO_EM_RESTART': '1', 04:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:00:53 INFO - 'PWD': '/builds/slave/test-pgo', 04:00:53 INFO - 'SHELL': '/bin/bash', 04:00:53 INFO - 'SHLVL': '1', 04:00:53 INFO - 'TERM': 'linux', 04:00:53 INFO - 'TMOUT': '86400', 04:00:53 INFO - 'USER': 'cltbld', 04:00:53 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:53 INFO - '_': '/tools/buildbot/bin/python'} 04:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:00:54 INFO - Cleaning up... 04:00:54 INFO - Return code: 0 04:00:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 04:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:00:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 04:00:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:00:54 INFO - Using env: {'DISPLAY': ':0', 04:00:54 INFO - 'HOME': '/home/cltbld', 04:00:54 INFO - 'LANG': 'en_US.UTF-8', 04:00:54 INFO - 'LANGUAGE': 'en_US:en', 04:00:54 INFO - 'LOGNAME': 'cltbld', 04:00:54 INFO - 'MAIL': '/var/mail/cltbld', 04:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:54 INFO - 'MOZ_NO_REMOTE': '1', 04:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:54 INFO - 'NO_EM_RESTART': '1', 04:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:00:54 INFO - 'PWD': '/builds/slave/test-pgo', 04:00:54 INFO - 'SHELL': '/bin/bash', 04:00:54 INFO - 'SHLVL': '1', 04:00:54 INFO - 'TERM': 'linux', 04:00:54 INFO - 'TMOUT': '86400', 04:00:54 INFO - 'USER': 'cltbld', 04:00:54 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:54 INFO - '_': '/tools/buildbot/bin/python'} 04:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:54 INFO - Downloading/unpacking psutil>=0.7.1 04:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 04:00:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:00:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 04:00:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:56 INFO - Installing collected packages: psutil 04:00:56 INFO - Running setup.py install for psutil 04:00:56 INFO - building 'psutil._psutil_linux' extension 04:00:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:00:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:00:56 INFO - building 'psutil._psutil_posix' extension 04:00:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:00:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:00:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:56 INFO - Successfully installed psutil 04:00:56 INFO - Cleaning up... 04:00:56 INFO - Return code: 0 04:00:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 04:00: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')]} 04:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00: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')]} 04:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:00:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 04:00:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:00:56 INFO - Using env: {'DISPLAY': ':0', 04:00:56 INFO - 'HOME': '/home/cltbld', 04:00:56 INFO - 'LANG': 'en_US.UTF-8', 04:00:56 INFO - 'LANGUAGE': 'en_US:en', 04:00:56 INFO - 'LOGNAME': 'cltbld', 04:00:56 INFO - 'MAIL': '/var/mail/cltbld', 04:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:56 INFO - 'MOZ_NO_REMOTE': '1', 04:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:56 INFO - 'NO_EM_RESTART': '1', 04:00:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:00:56 INFO - 'PWD': '/builds/slave/test-pgo', 04:00:56 INFO - 'SHELL': '/bin/bash', 04:00:56 INFO - 'SHLVL': '1', 04:00:56 INFO - 'TERM': 'linux', 04:00:56 INFO - 'TMOUT': '86400', 04:00:56 INFO - 'USER': 'cltbld', 04:00:56 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:56 INFO - '_': '/tools/buildbot/bin/python'} 04:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:00:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:00:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:00:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:00:58 INFO - Installing collected packages: mozsystemmonitor 04:00:58 INFO - Running setup.py install for mozsystemmonitor 04:00:58 INFO - Successfully installed mozsystemmonitor 04:00:58 INFO - Cleaning up... 04:00:58 INFO - Return code: 0 04:00:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 04:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:00:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 04:00:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:00:58 INFO - Using env: {'DISPLAY': ':0', 04:00:58 INFO - 'HOME': '/home/cltbld', 04:00:58 INFO - 'LANG': 'en_US.UTF-8', 04:00:58 INFO - 'LANGUAGE': 'en_US:en', 04:00:58 INFO - 'LOGNAME': 'cltbld', 04:00:58 INFO - 'MAIL': '/var/mail/cltbld', 04:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:58 INFO - 'MOZ_NO_REMOTE': '1', 04:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:58 INFO - 'NO_EM_RESTART': '1', 04:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:00:58 INFO - 'PWD': '/builds/slave/test-pgo', 04:00:58 INFO - 'SHELL': '/bin/bash', 04:00:58 INFO - 'SHLVL': '1', 04:00:58 INFO - 'TERM': 'linux', 04:00:58 INFO - 'TMOUT': '86400', 04:00:58 INFO - 'USER': 'cltbld', 04:00:58 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:58 INFO - '_': '/tools/buildbot/bin/python'} 04:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:01:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:01:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 04:01:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01:01 INFO - Downloading docopt-0.6.1.tar.gz 04:01:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:01:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 04:01:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:01:01 INFO - Running setup.py install for blobuploader 04:01:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:01:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 04:01:01 INFO - Running setup.py install for requests 04:01:01 INFO - Running setup.py install for docopt 04:01:01 INFO - Successfully installed blobuploader requests docopt 04:01:01 INFO - Cleaning up... 04:01:01 INFO - Return code: 0 04:01:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:01: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')]} 04:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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')]} 04:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:01:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 04:01:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:01:01 INFO - Using env: {'DISPLAY': ':0', 04:01:01 INFO - 'HOME': '/home/cltbld', 04:01:01 INFO - 'LANG': 'en_US.UTF-8', 04:01:01 INFO - 'LANGUAGE': 'en_US:en', 04:01:01 INFO - 'LOGNAME': 'cltbld', 04:01:01 INFO - 'MAIL': '/var/mail/cltbld', 04:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:01 INFO - 'MOZ_NO_REMOTE': '1', 04:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:01 INFO - 'NO_EM_RESTART': '1', 04:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:01:01 INFO - 'PWD': '/builds/slave/test-pgo', 04:01:01 INFO - 'SHELL': '/bin/bash', 04:01:01 INFO - 'SHLVL': '1', 04:01:01 INFO - 'TERM': 'linux', 04:01:01 INFO - 'TMOUT': '86400', 04:01:01 INFO - 'USER': 'cltbld', 04:01:01 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:01 INFO - '_': '/tools/buildbot/bin/python'} 04:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:01:02 INFO - Running setup.py (path:/tmp/pip-8JZzgq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:01:02 INFO - Running setup.py (path:/tmp/pip-_pA_pF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:01:02 INFO - Running setup.py (path:/tmp/pip-CspLYV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:01:02 INFO - Running setup.py (path:/tmp/pip-87kZJQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:01:02 INFO - Running setup.py (path:/tmp/pip-oebfyg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:01:02 INFO - Running setup.py (path:/tmp/pip-xLkdD5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:01:02 INFO - Running setup.py (path:/tmp/pip-1pmNbd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:01:02 INFO - Running setup.py (path:/tmp/pip-cXRz_a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:01:02 INFO - Running setup.py (path:/tmp/pip-gPzVuU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:01:02 INFO - Running setup.py (path:/tmp/pip-Q7LRGC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:01:02 INFO - Running setup.py (path:/tmp/pip-IF8Lt2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:01:02 INFO - Running setup.py (path:/tmp/pip-okZZLG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:01:02 INFO - Running setup.py (path:/tmp/pip-fNdG7y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:01:03 INFO - Running setup.py (path:/tmp/pip-j3dfw3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:01:03 INFO - Running setup.py (path:/tmp/pip-m6Qunj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:01:03 INFO - Running setup.py (path:/tmp/pip-qBhflU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:01:03 INFO - Running setup.py (path:/tmp/pip-F3loDj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:01:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:01:03 INFO - Running setup.py install for manifestparser 04:01:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 04:01:03 INFO - Running setup.py install for mozcrash 04:01:03 INFO - Running setup.py install for mozdebug 04:01:03 INFO - Running setup.py install for mozdevice 04:01:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 04:01:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 04:01:03 INFO - Running setup.py install for mozfile 04:01:03 INFO - Running setup.py install for mozhttpd 04:01:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 04:01:03 INFO - Running setup.py install for mozinfo 04:01:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Running setup.py install for mozInstall 04:01:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Running setup.py install for mozleak 04:01:04 INFO - Running setup.py install for mozlog 04:01:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Running setup.py install for moznetwork 04:01:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Running setup.py install for mozprocess 04:01:04 INFO - Running setup.py install for mozprofile 04:01:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Running setup.py install for mozrunner 04:01:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 04:01:04 INFO - Running setup.py install for mozscreenshot 04:01:04 INFO - Running setup.py install for moztest 04:01:04 INFO - Running setup.py install for mozversion 04:01:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 04:01:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:01:05 INFO - Cleaning up... 04:01:05 INFO - Return code: 0 04:01:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:01:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 04:01:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:01:05 INFO - Using env: {'DISPLAY': ':0', 04:01:05 INFO - 'HOME': '/home/cltbld', 04:01:05 INFO - 'LANG': 'en_US.UTF-8', 04:01:05 INFO - 'LANGUAGE': 'en_US:en', 04:01:05 INFO - 'LOGNAME': 'cltbld', 04:01:05 INFO - 'MAIL': '/var/mail/cltbld', 04:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:05 INFO - 'MOZ_NO_REMOTE': '1', 04:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:05 INFO - 'NO_EM_RESTART': '1', 04:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:01:05 INFO - 'PWD': '/builds/slave/test-pgo', 04:01:05 INFO - 'SHELL': '/bin/bash', 04:01:05 INFO - 'SHLVL': '1', 04:01:05 INFO - 'TERM': 'linux', 04:01:05 INFO - 'TMOUT': '86400', 04:01:05 INFO - 'USER': 'cltbld', 04:01:05 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:05 INFO - '_': '/tools/buildbot/bin/python'} 04:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:01:05 INFO - Running setup.py (path:/tmp/pip-awSuj7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:01:05 INFO - Running setup.py (path:/tmp/pip-V_5gQJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:01:05 INFO - Running setup.py (path:/tmp/pip-rEqqek-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:01:05 INFO - Running setup.py (path:/tmp/pip-qD7zaQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:01:05 INFO - Running setup.py (path:/tmp/pip-T6CZIb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:01:05 INFO - Running setup.py (path:/tmp/pip-CEN1Hq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:01:05 INFO - Running setup.py (path:/tmp/pip-xhNt6S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:01:05 INFO - Running setup.py (path:/tmp/pip-O3QsYF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:01:05 INFO - Running setup.py (path:/tmp/pip-xdQaZm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 04:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:01:05 INFO - Running setup.py (path:/tmp/pip-mkOWux-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:01:06 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)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:01:06 INFO - Running setup.py (path:/tmp/pip-qd6ynD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:01:06 INFO - Running setup.py (path:/tmp/pip-Xf3S0s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:01:06 INFO - Running setup.py (path:/tmp/pip-VosCOH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:01:06 INFO - Running setup.py (path:/tmp/pip-nRpLsk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:01:06 INFO - Running setup.py (path:/tmp/pip-OGIHVT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:01:06 INFO - Running setup.py (path:/tmp/pip-giMCcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 04:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:01:06 INFO - Running setup.py (path:/tmp/pip-KPeR1P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 04:01:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 04:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:08 INFO - Downloading blessings-1.5.1.tar.gz 04:01:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 04:01:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 04:01:08 INFO - Installing collected packages: blessings 04:01:08 INFO - Running setup.py install for blessings 04:01:08 INFO - Successfully installed blessings 04:01:08 INFO - Cleaning up... 04:01:08 INFO - Return code: 0 04:01:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 04:01:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:01:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:01:08 INFO - Reading from file tmpfile_stdout 04:01:08 INFO - Current package versions: 04:01:08 INFO - argparse == 1.2.1 04:01:08 INFO - blessings == 1.5.1 04:01:08 INFO - blobuploader == 1.2.4 04:01:08 INFO - docopt == 0.6.1 04:01:08 INFO - manifestparser == 1.1 04:01:08 INFO - mozInstall == 1.12 04:01:08 INFO - mozcrash == 0.16 04:01:08 INFO - mozdebug == 0.1 04:01:08 INFO - mozdevice == 0.47 04:01:08 INFO - mozfile == 1.2 04:01:08 INFO - mozhttpd == 0.7 04:01:08 INFO - mozinfo == 0.9 04:01:08 INFO - mozleak == 0.1 04:01:08 INFO - mozlog == 3.1 04:01:08 INFO - moznetwork == 0.27 04:01:08 INFO - mozprocess == 0.22 04:01:08 INFO - mozprofile == 0.27 04:01:08 INFO - mozrunner == 6.11 04:01:08 INFO - mozscreenshot == 0.1 04:01:08 INFO - mozsystemmonitor == 0.0 04:01:08 INFO - moztest == 0.7 04:01:08 INFO - mozversion == 1.4 04:01:08 INFO - psutil == 3.1.1 04:01:08 INFO - requests == 1.2.3 04:01:08 INFO - wsgiref == 0.1.2 04:01:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:01:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 04:01:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:01:08 INFO - Using env: {'DISPLAY': ':0', 04:01:08 INFO - 'HOME': '/home/cltbld', 04:01:08 INFO - 'LANG': 'en_US.UTF-8', 04:01:08 INFO - 'LANGUAGE': 'en_US:en', 04:01:08 INFO - 'LOGNAME': 'cltbld', 04:01:08 INFO - 'MAIL': '/var/mail/cltbld', 04:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:08 INFO - 'MOZ_NO_REMOTE': '1', 04:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:08 INFO - 'NO_EM_RESTART': '1', 04:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:01:08 INFO - 'PWD': '/builds/slave/test-pgo', 04:01:08 INFO - 'SHELL': '/bin/bash', 04:01:08 INFO - 'SHLVL': '1', 04:01:08 INFO - 'TERM': 'linux', 04:01:08 INFO - 'TMOUT': '86400', 04:01:08 INFO - 'USER': 'cltbld', 04:01:08 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:08 INFO - '_': '/tools/buildbot/bin/python'} 04:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 04:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 04:01:08 INFO - Cleaning up... 04:01:08 INFO - Return code: 0 04:01:08 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 04:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2729030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28898a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29567d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, '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': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', '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 04:01:08 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', 'jsonschema'] in /builds/slave/test-pgo/build 04:01:08 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 jsonschema 04:01:08 INFO - Using env: {'DISPLAY': ':0', 04:01:08 INFO - 'HOME': '/home/cltbld', 04:01:08 INFO - 'LANG': 'en_US.UTF-8', 04:01:08 INFO - 'LANGUAGE': 'en_US:en', 04:01:08 INFO - 'LOGNAME': 'cltbld', 04:01:08 INFO - 'MAIL': '/var/mail/cltbld', 04:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:08 INFO - 'MOZ_NO_REMOTE': '1', 04:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:08 INFO - 'NO_EM_RESTART': '1', 04:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:01:08 INFO - 'PWD': '/builds/slave/test-pgo', 04:01:08 INFO - 'SHELL': '/bin/bash', 04:01:08 INFO - 'SHLVL': '1', 04:01:08 INFO - 'TERM': 'linux', 04:01:08 INFO - 'TMOUT': '86400', 04:01:08 INFO - 'USER': 'cltbld', 04:01:08 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:08 INFO - '_': '/tools/buildbot/bin/python'} 04:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:09 INFO - Downloading/unpacking jsonschema 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:01:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 04:01:10 INFO - Downloading/unpacking functools32 (from jsonschema) 04:01:10 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 04:01:10 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 04:01:10 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 04:01:10 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 04:01:10 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 04:01:10 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 04:01:10 INFO - Downloading functools32-3.2.3-2.tar.gz 04:01:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:01:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 04:01:11 INFO - warning: no files found matching '*.txt' 04:01:11 INFO - no previously-included directories found matching 'build' 04:01:11 INFO - no previously-included directories found matching 'dist' 04:01:11 INFO - no previously-included directories found matching '.git*' 04:01:11 INFO - Installing collected packages: jsonschema, functools32 04:01:11 INFO - Running setup.py install for functools32 04:01:11 INFO - warning: no files found matching '*.txt' 04:01:11 INFO - no previously-included directories found matching 'build' 04:01:11 INFO - no previously-included directories found matching 'dist' 04:01:11 INFO - no previously-included directories found matching '.git*' 04:01:11 INFO - Successfully installed jsonschema functools32 04:01:11 INFO - Cleaning up... 04:01:11 INFO - Return code: 0 04:01:11 INFO - Running post-action listener: _resource_record_post_action 04:01:11 INFO - Running post-action listener: _start_resource_monitoring 04:01:11 INFO - Starting resource monitoring. 04:01:11 INFO - ##### 04:01:11 INFO - ##### Running install step. 04:01:11 INFO - ##### 04:01:11 INFO - Running pre-action listener: _resource_record_pre_action 04:01:11 INFO - Running main action method: install 04:01:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:01:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:01:11 INFO - Reading from file tmpfile_stdout 04:01:11 INFO - Detecting whether we're running mozinstall >=1.0... 04:01:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 04:01:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 04:01:11 INFO - Reading from file tmpfile_stdout 04:01:11 INFO - Output received: 04:01:11 INFO - Usage: mozinstall [options] installer 04:01:11 INFO - Options: 04:01:11 INFO - -h, --help show this help message and exit 04:01:11 INFO - -d DEST, --destination=DEST 04:01:11 INFO - Directory to install application into. [default: 04:01:11 INFO - "/builds/slave/test-pgo"] 04:01:11 INFO - --app=APP Application being installed. [default: firefox] 04:01:11 INFO - mkdir: /builds/slave/test-pgo/build/application 04:01: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'] 04:01: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 04:01:26 INFO - Reading from file tmpfile_stdout 04:01:26 INFO - Output received: 04:01:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 04:01:26 INFO - Running post-action listener: _resource_record_post_action 04:01:26 INFO - ##### 04:01:26 INFO - ##### Running run-tests step. 04:01:26 INFO - ##### 04:01:26 INFO - Running pre-action listener: _resource_record_pre_action 04:01:26 INFO - Running main action method: run_tests 04:01:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 04:01:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 04:01:26 INFO - Python 2.7.3 04:01:26 INFO - Return code: 0 04:01:26 INFO - grabbing minidump binary from tooltool 04:01: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')]} 04:01: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 0x29e5640>, '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 0x29e6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e69d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 04:01: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 04:01: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 04:01:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:01:26 INFO - Return code: 0 04:01:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 04:01:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 04:01:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 04:01:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 04:01:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 04:01: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/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 04:01: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/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost 04:01:26 INFO - Using env: {'DISPLAY': ':0', 04:01:26 INFO - 'HOME': '/home/cltbld', 04:01:26 INFO - 'LANG': 'en_US.UTF-8', 04:01:26 INFO - 'LANGUAGE': 'en_US:en', 04:01:26 INFO - 'LOGNAME': 'cltbld', 04:01:26 INFO - 'MAIL': '/var/mail/cltbld', 04:01:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:01:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 04:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:26 INFO - 'MOZ_NO_REMOTE': '1', 04:01:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:26 INFO - 'NO_EM_RESTART': '1', 04:01:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:01:26 INFO - 'PWD': '/builds/slave/test-pgo', 04:01:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 04:01:26 INFO - 'SHELL': '/bin/bash', 04:01:26 INFO - 'SHLVL': '1', 04:01:26 INFO - 'TERM': 'linux', 04:01:26 INFO - 'TMOUT': '86400', 04:01:26 INFO - 'USER': 'cltbld', 04:01:26 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1448451403.44458-548766397', 04:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:26 INFO - '_': '/tools/buildbot/bin/python'} 04:01: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/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost'] with output_timeout 3600 04:01:26 INFO - mozversion INFO | application_buildid: 20151125023019 04:01:26 INFO - mozversion INFO | application_changeset: 42e781cabc64ee811fad3962ecad5a8cb5a7cacc 04:01:26 INFO - mozversion INFO | application_display_name: Nightly 04:01:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:01:26 INFO - mozversion INFO | application_name: Firefox 04:01:26 INFO - mozversion INFO | application_remotingname: firefox 04:01:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 04:01:26 INFO - mozversion INFO | application_vendor: Mozilla 04:01:26 INFO - mozversion INFO | application_version: 45.0a1 04:01:26 INFO - mozversion INFO | platform_buildid: 20151125023019 04:01:26 INFO - mozversion INFO | platform_changeset: 42e781cabc64ee811fad3962ecad5a8cb5a7cacc 04:01:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 04:01:26 INFO - mozversion INFO | platform_version: 45.0a1 04:01:26 INFO - 2015-11-25 04:01:26,438 DEBUG : using testdate: 1448452886 04:01:26 INFO - 2015-11-25 04:01:26,438 DEBUG : actual date: 1448452886 04:01:26 INFO - 2015-11-25 04:01:26,450 INFO : starting webserver on 'localhost:48298' 04:01:26 INFO - 2015-11-25 04:01:26,451 INFO : Starting test suite talos-linux64-ix-042 04:01:26 INFO - 2015-11-25 04:01:26,451 INFO : Starting test dromaeo_css 04:01:26 INFO - 2015-11-25 04:01:26,452 DEBUG : operating with platform_type : linux_ 04:01:26 INFO - 2015-11-25 04:01:26,452 INFO : Initialising browser for dromaeo_css test... 04:01:26 INFO - 2015-11-25 04:01:26,469 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIFuq9j/profile http://localhost:48298/getInfo.html 04:01:31 INFO - 2015-11-25 04:01:31,273 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:01:31 INFO - 2015-11-25 04:01:31,273 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:01:31 INFO - 2015-11-25 04:01:31,273 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:01:31 INFO - 2015-11-25 04:01:31,273 DEBUG : BROWSER_OUTPUT: __metrics 04:01:31 INFO - 2015-11-25 04:01:31,503 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:01:33 INFO - 2015-11-25 04:01:33,140 INFO : Browser initialized. 04:01:33 INFO - 2015-11-25 04:01:33,141 INFO : Running cycle 1/1 for dromaeo_css test... 04:01:33 INFO - 2015-11-25 04:01:33,141 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIFuq9j/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:01:44 INFO - 2015-11-25 04:01:44,958 DEBUG : BROWSER_OUTPUT: RSS: Main: 138870784 04:01:44 INFO - 2015-11-25 04:01:44,958 DEBUG : BROWSER_OUTPUT: 04:02:35 INFO - 2015-11-25 04:02:35,647 DEBUG : BROWSER_OUTPUT: 1448452955645 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] 04:04:45 INFO - 2015-11-25 04:04:45,735 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48298/tests/dromaeo/cssquery-ext.html) 04:04:46 INFO - 2015-11-25 04:04:46,867 DEBUG : BROWSER_OUTPUT: RSS: Main: 265560064 04:04:46 INFO - 2015-11-25 04:04:46,867 DEBUG : BROWSER_OUTPUT: 04:07:49 INFO - 2015-11-25 04:07:49,223 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/dromaeo/cssquery-ext.html (next: http://localhost:48298/tests/dromaeo/cssquery-jquery.html) 04:07:49 INFO - 2015-11-25 04:07:49,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 222777344 04:07:49 INFO - 2015-11-25 04:07:49,796 DEBUG : BROWSER_OUTPUT: 04:10:51 INFO - 2015-11-25 04:10:51,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48298/tests/dromaeo/cssquery-mootools.html) 04:10:52 INFO - 2015-11-25 04:10:52,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 222273536 04:10:52 INFO - 2015-11-25 04:10:52,320 DEBUG : BROWSER_OUTPUT: 04:13:54 INFO - 2015-11-25 04:13:54,225 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48298/tests/dromaeo/cssquery-prototype.html) 04:13:54 INFO - 2015-11-25 04:13:54,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 201457664 04:13:54 INFO - 2015-11-25 04:13:54,787 DEBUG : BROWSER_OUTPUT: 04:16:57 INFO - 2015-11-25 04:16:57,299 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48298/tests/dromaeo/cssquery-yui.html) 04:16:57 INFO - 2015-11-25 04:16:57,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 206069760 04:16:57 INFO - 2015-11-25 04:16:57,865 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,363 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/dromaeo/cssquery-yui.html (next: http://localhost:48298/tests/dromaeo/cssquery-dojo.html) 04:20:00 INFO - 2015-11-25 04:20:00,610 DEBUG : BROWSER_OUTPUT: RSS: Main: 270172160 04:20:00 INFO - 2015-11-25 04:20:00,610 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,611 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:20:00 INFO - 2015-11-25 04:20:00,611 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:20:00 INFO - 2015-11-25 04:20:00,611 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:20:00 INFO - 2015-11-25 04:20:00,611 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:20:00 INFO - 2015-11-25 04:20:00,613 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3192.8563214655337;3214.1643172775102;3249.610046794385;3263.0374039658313;3286.753493487988;5124.410692770332;5253.7373131343475;5333.946660533394;5362.249285100084;5400.108982017972;5273;5351.57187425006;5402.864928376793;5445.101558242895;5447.073997420438;4909.8772530686665;4916.090523253191;4940.530649588283;4945.085159245533;4994.500549944994;4747.90504189916;4987.675801072919;4988.600911927056;5002.499750025005;5071.239314102877;4820.999999999983;4823.614110871139;4874.536918992725;4938.555530002282;4948.356713627241;4925.827596034157;4926.1625523660705;4927.260910863399;4938;4968.378952630921;4878.268259761029;4881.609471242292;4889.07107649549;4890.070886531547;4947.381577302837;5093.236014597803;5184.766685499161;5204.583633309327;5284.365876094855;5318.680879147225;5850.76596936122;5854.5023672987845;5855.06318988964;5860.238169038041;5865.677387743673;5472.644278121909;5861.85345366365;5877.617954832965;5883.558733094992;5894.351621321651;5413.133898576249;5415.512603865671;5434.103372943459;5510.173473978935;5512.5589952803675;5911.349751527328;5911.9113213301835;5911.970440147772;5919.082542205965;5956.791512297049;5728.083506638918;5731.426857314319;5739.225204597409;5741.655500669973;5750.7987220447085;5621.381648018714;5803.883922321615;5805.36141024487;5828.912566311508;5944.405559444108;5841;5853.09277062056;5857.033589457692;5858.033424484997;5863.970680146572;5854.853628659318;5858.50202732764;5867.589268751231;5873.618214816109;5874.618149820248;4373.103513779731;4427.22523558376;4442.8913738019555;4501.212287849613;4509.954900451019;4019.1157945252;4024.2353952748886;4027.7381970171846;4029.5567487576345;4087.346024636044;5522.2544956430365;5536.557075433956;5540.667559946335;5543.196236545678;5598.1322894951345;5723.255976723;5765.135229715576;5828.766849326064;5835.474807267281;5887.558433117533;5483;5493.176023596413;5494.752736126884;5519.834404967857;5524.613277070566;5106.361704786902;5117.078925793393;5128.8717782055;5131.3863179574;5169.612279079084;5722.570807189478;5828.242328497353;5828.59199856006;5947.464728174485;6037.215162028908;5758.913616295843;5769.278840144982;5797.159411885337;5797.855053623524;5806.1000544915605;5928.67392293428;5962.07587823888;5979.252593425822;5980.671063091398;5982.252218472691;5598.244237027949;5685.374608793112;5729.312482502127;5733.770649173986;5734.598578099493;5851.824445266649;5887;5919.378465261086;5927.229460170151;5941.702914854153;5730.627509211888;5746.655200687972;5751.568632352507;5752.6836024019085;5754.798582049608;5469.15228139639;5560.499555039904;5561.5272701820795;5581.079121944834;5675.545956323567;5597.93639208549;5617.606767526234;5629.549636029026;5631.577631677558;5635.0702134147405;5721.113227449752;5755.654660720203;5765.365809760839;5770.480656740913;5820.82537523875;5425.348958125051;5482.39693633708;5541.113421852484;5563.415841336763;5583.74873130702 04:20:00 INFO - 2015-11-25 04:20:00,616 DEBUG : BROWSER_OUTPUT: |1;ext.html;552.687731431741;596.9701514924254;601.4136217188242;603.0682595390122;603.7343568829716;30731.539026914616;39158.216835663305;39522.209555808935;39718.40422393666;39779.40330895039;41663.58336416635;42141.36787948183;42173.367399489034;42229.15541689164;42259.36610950838;7582.128055273659;7700;8731;8882.1117888211;8889.555522223864;10521.368717876914;10787.730306742316;10795.78408431831;10797.244192906499;10800;10451.895481045169;10560.155187585013;10571.365718056903;10604.78790424191;10607.204459665518;7801;7975.7607271781935;10413.687589372294;10590.36457812526;10893.782124357547;10299.53652085658;10387.792244155075;10440.582376704922;10444.321119127233;10455.634052808191;39226.21547569061;39289.21421571581;39641.60358396408;39648;39669.40495892564;3070.723634872872;3105.5652208690803;3298.472244440878;3511.613722490524;3824.445455408978;30282.84858575693;30362;30801.075967721004;30816.075517734505;30887.845560772275;3925.7584416229665;3936.6063393660693;3937.1141493164096;3976.681865450757;4011.8796436106963;271173;271934;273140;273617;274085.999999998;56137;57314.140287895716;59073.40926590679;59379.703101484214;59441.70279148577;68895.31104688889;70153.64923175453;70615.64692176609;70697.64651176814;70716.64641676759;15037.849621503863;15263.923680381527;15281.694366112615;15282.083075015535;15291.388344466097;11407.600733974437;11469.139814513945;11474.885251147381;11478.483468243949;11481.655550333504;19344.32294869701;19433.588894524022;19443.23944294028;19601.901990489958;19784;7549.811254718676;7672.194419585975;7735.961320193475;7799.766007019798;7820.648070836826;922.6125027488471;923.3490389275545;925.8842644669417;947.3131979314942;947.8767760191131;28436.857815710788;28469.715302847122;28504.71495285062;28619.28451788722;28955.42089158247;1000.8798944126752;1012.3217444312328;1064.1539975719322;1070.4112737994071;1079.9190060745475;14843.777343339858;14871.553853384416;14940.32768525419;15132;15159.317830697644;301710;306627;307858;307899;308218;52818.47181528212;52864;52889.206661900105;52950.73524632352;53016.46983530193;1327.6680829792551;1330.5808670268834;1347.063790665478;1350.8581598931971;1352.9729405411836;27032.32419189536;27919.581206281517;28099.157025289274;28275.717242827308;30916.53625195579;48327.51672483371;48723.75638121787;48904.51095488999;48949.51050489592;48997.2650410237;269290;271356;271983;272041;272306;27990.160295191177;28482.85758571194;30308.696913030588;30915.69084309218;31699.366012679155;39246;39276.803615981735;40054.1989160221;40067.39898901577;40271.59728402796;35051.82474087613;35505.28989420249;36209.63790362168;36404.27191456209;36699.63300366962;29680.406391872475;34481;35627.64372356243;36772.81613591915;36938.63061369459;32977;33234.50148247826;33398.49902251517;33443;34086.31827363488;29761.25596860096;30705.92529261465;31019.224519386295;31044.53433198549;31085.68914310828;23592.410189745395;23692.763072369744;24707.876460617583;24923.626145608192;25322.873385632953 04:20:00 INFO - 2015-11-25 04:20:00,618 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.3674813953372;359.2312451354102;360.63395653411794;360.78713559000187;362.18508356198555;14165.71668566628;14208.502702405418;16497.340106395746;16736.665266694657;17381;11213.439328033586;11447.713807154805;16721.91639041803;17587.82412175878;17603.207855646495;4816.710997340162;4902.803887844482;4918.827841025565;4923.18767403378;4927.457979622239;3742.3825068863603;3843.730938834283;4888.560029597335;4912.803487860481;5366.677999320033;5424.83725488236;5453.127499600065;5477.890442191154;5483;5487.533559647415;5370.91943620846;5428.077226871442;5908.822735317947;5935.9406405935815;5942.970285148546;5283.022640811462;5286.867828304285;5293.86765330866;5297.099493086184;5297.894042119175;19760.505987350287;20274.69587956182;20525.17899284027;21351.893240533853;21407.892960535097;2000.4998750312423;2022.464047027539;2069.1206237349065;2069.2343832781967;2113.6406810842195;9425.905740942571;9535.952320238424;9746.658866939622;9946.950265248628;10043.949780251125;1688.7804585403942;1841.4291569613329;1855.239351865742;1868.0753027251462;1900.914458849355;62190.68904655448;63276.36723632797;63436;63497.99999999954;64129.35870641281;9887.851682224773;10173.44046077458;10199.694009179737;10236.385816850863;10317;13544.796828047587;13558.932205339108;13577.59267221985;13595.592132236048;13599.52401665937;7134.180783900176;7203.459740519482;7410.592417416986;7470.887936680954;7656.502327348816;6715.664216789141;6740.157480314961;6750.932490675129;6798.898016529756;6803.48973826965;4397.6042156206095;4427.446569178853;4428.667849911205;4441.644668426583;4465.151621191963;1709.0429359558686;1773.4502304285713;1779.8576113910856;1799.8110198429238;1855.6752568300763;417.19689597524643;800.2237829305565;886.9645214191489;907.9182873541413;933.8739270198572;10428;10684.46577671129;10730.624428144978;10983.670489885317;11031.393273369882;400.71949635255436;446.8471343932646;448.49544262704455;454.8135264541588;456.21303251890885;5684.886302274014;5998.880022399528;6111.449969502677;6222.315545290105;6227.937720622826;499143;518383;525725;530652;531880;44238.557614423444;72034;73012.52406069566;77643.2235677636;77884;1134.183387960667;1158.3050169897995;1159.8550181227347;1163.1625229834674;1166.0787977497682;15419;15767.290471928562;16140.596485087966;16215.351385944901;16356.509304720877;34146.31707365889;34369;35032.82483587566;35639;36261;43299.13401732011;44798;45338.31992520181;45510.54489455063;49399.75300123476;9284.62861485533;9340.53297335144;9556.331056826233;9565.426074435556;9665.3234273603;11496.655100347003;11612.245204061708;11667.299962002307;11768.235064720766;11773.17587768848;13990.720185596434;14511.274436277932;14732.926335368684;14830.40678372853;15165.924170379078;14563.271836408348;14602.707945840812;14628.707425851637;14743.336549855068;15186.544403667465;11915.106367022334;12213.572524961939;13068.869311306766;14969.550913472613;15261.389544418544;9475.715728528156;9538.523073846141;9694.175995040501;9718.076782705632;9726.270529710158;10165.898341016495;10275.794484110425;13406.32968351598;13991;14422.206778626856 04:20:00 INFO - 2015-11-25 04:20:00,619 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1490.3219035338923;1497.348653335799;1545.714042902064;1625.1549194418903;1698.991505042475;6618.669066546671;7480.663370148342;7838.451308408399;7861.842763144734;7872.8819067713885;7661.770146895588;7806.14132445432;7828.725994590191;7831.373490120791;7837.764867053983;2002.7997200280001;2034.216826521785;2254.6730724045005;2292.3123063081093;2329.5690297295055;2304.9654255186188;2310.387747246981;2312.710911136108;2314.652802079693;2316.791488766011;2251;2275.988620056906;2292.59879521084;2298.2645553422826;2299.6895419118373;1999.8400127989798;2039.4799326171806;2214.0700905619597;2248.6289762189217;2253.121282699732;2247.9438014049615;2278;2302.861828290308;2314.93055208344;2321.756215597372;7791.376689864797;7874.5668988205625;7893.881591776128;7898.60506974649;7904;4740.454847692525;4798.160321943646;4835.347228124193;4840.806367745286;4842.733649649268;3089.830059346735;3121.0636808957447;3162.193640621627;3178.1895616617617;3278.065751260879;1086.0659832544006;1119.2053641914251;1141.4406940599129;1799.127423199747;1886.5755205078747;10510.894891051068;10590.205734569938;10604.416757078357;10617.469126543641;10619.097376722988;4160.209560183555;4220.2192594370135;4248.171606536757;4272.700910936204;4280.036991676848;8641.611127499278;8670.349723770742;8682.86975695365;8725.432846864933;8738.781530461789;4779.761011949388;4781.832635857728;4788.592969597623;4803.327534145223;4807.038592281488;4387.034852332482;4420.668449866273;4421.336799480104;4433.179861725526;4435.356873253425;3446.913827154341;3485.1809824691177;3512.9297414051575;3520.331137083946;3522.559680039995;1053.9367637941748;1066.8666416697913;1070.3845288958723;1074.5540600650643;1077.9407132607625;971.5676523946917;995.9302848800514;996.6163027234422;997.0976266478848;1273.8598754137038;6329.873402531924;7458.738944137038;7685.769426917202;7692.19231980634;7693.384529237647;575.7725698349128;576.7952376906222;577.6707276852238;587.4419301663437;595.0776296740036;5821.708914554255;5857.648541087548;5875.471207591338;5891.05743081105;5913.083472061838;9302.441853488812;9361.62553497866;9379.437233765995;9481.194098501566;9496.810063798684;9173.082691730908;9260.073992600821;9376.437413755197;9383.296252780932;9387.10822471864;7076.363127318566;7096.787096387014;7160;7208.135023797073;7216.819579510554;6925.757598484029;7046.2249152594195;7120.821979450555;7122.928770712227;7128.429725622042;8078.515289082674;8185.754427367188;8229.753107406787;8230.588470576567;8306.667733290842;8312.08567057611;8315.584220789058;8470.957645211734;8491.830163396575;8498.53258070812;6523.543351965317;6600.768973085919;6613.5370524062855;6614.570052946543;6619.50353723463;7525.3227209551405;7526.134494533198;7591.392688585011;7601.429892758176;7607.315341619281;6756.391924726799;7035.929640703527;7113.359797618033;7130.607816570138;7170.605616691132;6762.391384775394;6947;6985.685644145924;7013.263607321158;7081.362677358857;6321.873562528632;6457.192850893638;7043.542169758949;7080.610566418896;7099.396551292992;6304.905426418516;6433.195850518685;6459.61242325462;6491.870162596816;6505.21937367519;6516.413522782972;6531.575447596081;6567.868642627025;6583.30875258091;6593.307702691149 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: |4;prototype.html;337.7720038973693;344.9896503104906;347.40073373430835;353.12425185539877;356.3176516970001;5922.2301100856885;5957.940420595798;5966.9557827380195;6007.15899774032;6030.75876964922;5696.202531645563;5797.246357973458;5812.1281807728865;5932.288125424944;5956.910646340309;4602.81588736451;4628.930566041512;4630.97684511577;4666.2767271073035;4675.392199014123;4613.769311534426;4617.538246175389;4634.953650463486;4638.188317044521;4664.55686709762;4359.607635312821;4432.135733531963;4491.595756381925;4578.519255478177;4586.312053192031;4617.191991401492;4617.838375656869;4630.560096790817;4631.189541830183;4641.675082744209;4080.755154690728;4082.265192265391;4083.1629515949458;4087.9386809197886;4090.141070375225;5699.088145896637;5701.315842098975;5702.600817942745;5708.543316534668;5712;5262.315898933152;5464.3442786865435;5466.808661696821;5474.7262636868;5475.014497390468;6397.1363865877975;6419.26178489475;6435.388638079375;6435.420812126931;6438.742450301982;4598.126355992351;4600.608948239404;4601.28680054592;4606.493285738566;4607.815687372501;6370.394812492806;6464.256610489807;6489.545731798776;6505.4795616350575;6533.771318003847;4624;4630.9536904631195;4636.976815115903;4637.095766325601;4637.536246375335;4687.109449204709;4691.366665500113;4692.554207350297;4693.601043911238;4696.7651617419;5346;5346.064438723208;5350.491703288181;5354.544863686552;5355.571554275649;6282.4974002080635;6351.333110023474;6444.226692796894;6458.869697802959;6476.158099447041;1653.3469279634478;1689.2736123467027;1689.991550042242;1690.3492155520214;1691.3150174179477;1126.5662719852917;1152.1071169843271;1153.0832987774738;1172.0740614914293;1173.988260117405;6145.0475176346845;6150.7539698411565;6155.815325540241;6162.445379915829;6168.568200225941;6591.373819487141;6611.206655201407;6618.4705223582;6618.8345291368105;6627.668616569153;5959.0763431668165;6047.879042419127;6076.058210977306;6198.659073750987;6235.469985051322;3587.0673624857723;3589.335972845032;3600.6399360063783;3612.1150318172217;3613.674769270778;6895.862082758417;6896.689648965808;6902.171739391305;6907.516473846783;6908.723651054002;6684.16447944007;6852.451803855779;6865.210500792373;6867.450603951672;6876.724931002704;6876.140482439695;6901.861962760717;6902.758403455855;6903.447724182053;6905.1713794344;6592.604443733391;6657.766978155935;6662.966685166543;6670.83322916931;6677.465802735866;6422.550421470639;6617.536772425884;6661.233958094881;6670.199576050905;6677.09859169006;6928.757493487704;6931.410830079298;6933.826654333682;6940.13248343957;6945.340192681687;4543.227651299353;4577.35215322477;4579.129965306581;4580.519045500175;4585.037142200042;6595.076689263603;6705.832354191184;6720.46236301085;6725.125733654593;6726.461883049246;6533.705983230861;6540.672966351568;6542.803715888531;6543.5419520633095;6546.672666366758;6351.96824015877;6424.51816113784;6441.033844923299;6454.19322584677;6458.095866578588;6338.461230795249;6402.295747467681;6404.1674582304;6404.871902562016;6413.486921046212;6549.345065493603;6551.082848401323;6555.737770489127;6557.2131344239;6562.179727534059;6237;6371.39471750183;6392.360763923756;6395.072714456284;6399.104125422352 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: |5;yui.html;816.7427260412966;829.2412442615008;840.5755093677692;852.7995920958574;863.438764802878;13693.24687142208;14069.788953165698;14082.0846644968;14107.365168567414;14122.576322710322;13154.671133221675;13248.867511324885;13282.070255082148;13313.80029299559;13351.666208344795;680.1124532485105;825.4304529874397;838.8322335532887;839.4040231435657;839.7102999465175;692.9341712537301;812.3947658994058;831.4678605692362;831.4969443486694;833.9291160251386;867.4708427859033;878.2578720980761;881.6958149438466;884.3411658314565;884.3986089459165;707.8478127202676;723.1683563901503;849.1975083546051;875.1991927386434;875.8905136857893;644.0339490763854;644.3202421445374;644.5423749138117;644.716324817079;645.0195702532125;13506.662333441644;13657.112287701368;13663.590092297247;13666.18002919828;13677.31613419335;12088.456019479145;12561.434735436836;12645.683857903534;12693.873061269362;12992.935035324857;8833.469991800448;9250.213731832735;9408.858867116998;9432.434053956717;9519.904800951972;3581.7850928944345;3644.5262115925025;3647.1429214134655;3654.28741395428;3655.0496870813513;48025.27962080572;48129.278060829114;48132.759336203446;48206.27690584644;48253.27620085701;8864;8976.102389760972;9048.366614336934;9236.5843537041;9456.858147127661;19031.238750449826;19035.809641903405;19057.142428590745;19057.714134287995;19059;7188.065551478379;7206.639668016578;7219.386352160125;7337.302956219258;7467.887981680279;3721.5191135347754;3877.7867217302755;4089.4070359798257;4167;4192.874213773592;2965.7182567656037;2986.8357240351556;3021.4561378952;3023.9567349264466;3028.454878121915;529.2061907139291;530.082956485275;542.7638977044998;555.1506195520859;575.3441077172027;522.1593234891822;525.90796610593;551.5918220516788;600.2466904035408;638.3839594790957;7854.253845884632;8157.592120394075;8272.710455134162;8364.37267204962;8440.071592124861;548.9807856724996;591.8905002574537;611.8470382404399;672.5292295393147;687.189116842131;3797.734158608871;3803.6196380362294;3843.442700808423;3972.205504185644;4079.938800917989;56716;56917.43082569204;57214.14178787322;57310;57701;50764;50936.49063509391;51689.741551292005;51993.220101698505;52023.47976520186;45051.324230136575;46540.534594654295;46603.76698116556;46721;47744.5225547747;7880.881786773318;7923.603819809102;8200.58997050157;8314.459560128573;8516.489010659381;37725.4341184877;38045;38668;38809.223815522964;39509;45818.541814582764;46021.30968035415;46349.536504634525;47041.76479117719;47425.76287118543;10228.532953105067;10821.458927053458;10998.945005275242;11348.86402035429;11388.032017278623;10772.838407423738;10960.506777195205;10983.39591322485;11030.172737044593;11321.547138114713;10768.892311076788;11039.116870650168;11057.944710276397;11084.334939903632;11143.72140696489;10356.171506279328;10782.08352290064;10931.125509959025;11169.888301116885;11188.496517656888;10065.295429319878;10228.335158214692;10232.23258255619;10554;10569.260151789302;9260;9540.809183816424;9625.422474651543;9647.228221742384;9688.079632434907;8755.824883502168;8888.955555222183;9074.909250907407;9507.191888689262;9571.664991725534 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,898 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: __startTimestamp1448454000610__endTimestamp 04:20:00 INFO - 2015-11-25 04:20:00,623 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,624 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:20:00 INFO - 2015-11-25 04:20:00,624 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:20:00 INFO - 2015-11-25 04:20:00,624 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,624 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5393.28 Median:5581.08 stddev:597.51 (10.7%) stddev-sans-first:573.86 04:20:00 INFO - 2015-11-25 04:20:00,625 DEBUG : BROWSER_OUTPUT: Values: 3192.9 3214.2 3249.6 3263.0 3286.8 5124.4 5253.7 5333.9 5362.2 5400.1 5273.0 5351.6 5402.9 5445.1 5447.1 4909.9 4916.1 4940.5 4945.1 4994.5 4747.9 4987.7 4988.6 5002.5 5071.2 4821.0 4823.6 4874.5 4938.6 4948.4 4925.8 4926.2 4927.3 4938.0 4968.4 4878.3 4881.6 4889.1 4890.1 4947.4 5093.2 5184.8 5204.6 5284.4 5318.7 5850.8 5854.5 5855.1 5860.2 5865.7 5472.6 5861.9 5877.6 5883.6 5894.4 5413.1 5415.5 5434.1 5510.2 5512.6 5911.3 5911.9 5912.0 5919.1 5956.8 5728.1 5731.4 5739.2 5741.7 5750.8 5621.4 5803.9 5805.4 5828.9 5944.4 5841.0 5853.1 5857.0 5858.0 5864.0 5854.9 5858.5 5867.6 5873.6 5874.6 4373.1 4427.2 4442.9 4501.2 4510.0 4019.1 4024.2 4027.7 4029.6 4087.3 5522.3 5536.6 5540.7 5543.2 5598.1 5723.3 5765.1 5828.8 5835.5 5887.6 5483.0 5493.2 5494.8 5519.8 5524.6 5106.4 5117.1 5128.9 5131.4 5169.6 5722.6 5828.2 5828.6 5947.5 6037.2 5758.9 5769.3 5797.2 5797.9 5806.1 5928.7 5962.1 5979.3 5980.7 5982.3 5598.2 5685.4 5729.3 5733.8 5734.6 5851.8 5887.0 5919.4 5927.2 5941.7 5730.6 5746.7 5751.6 5752.7 5754.8 5469.2 5560.5 5561.5 5581.1 5675.5 5597.9 5617.6 5629.5 5631.6 5635.1 5721.1 5755.7 5765.4 5770.5 5820.8 5425.3 5482.4 5541.1 5563.4 5583.7 04:20:00 INFO - 2015-11-25 04:20:00,625 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,625 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45737.56 Median:30916.11 stddev:74130.85 (239.8%) stddev-sans-first:74261.17 04:20:00 INFO - 2015-11-25 04:20:00,625 DEBUG : BROWSER_OUTPUT: Values: 552.7 597.0 601.4 603.1 603.7 30731.5 39158.2 39522.2 39718.4 39779.4 41663.6 42141.4 42173.4 42229.2 42259.4 7582.1 7700.0 8731.0 8882.1 8889.6 10521.4 10787.7 10795.8 10797.2 10800.0 10451.9 10560.2 10571.4 10604.8 10607.2 7801.0 7975.8 10413.7 10590.4 10893.8 10299.5 10387.8 10440.6 10444.3 10455.6 39226.2 39289.2 39641.6 39648.0 39669.4 3070.7 3105.6 3298.5 3511.6 3824.4 30282.8 30362.0 30801.1 30816.1 30887.8 3925.8 3936.6 3937.1 3976.7 4011.9 271173.0 271934.0 273140.0 273617.0 274086.0 56137.0 57314.1 59073.4 59379.7 59441.7 68895.3 70153.6 70615.6 70697.6 70716.6 15037.8 15263.9 15281.7 15282.1 15291.4 11407.6 11469.1 11474.9 11478.5 11481.7 19344.3 19433.6 19443.2 19601.9 19784.0 7549.8 7672.2 7736.0 7799.8 7820.6 922.6 923.3 925.9 947.3 947.9 28436.9 28469.7 28504.7 28619.3 28955.4 1000.9 1012.3 1064.2 1070.4 1079.9 14843.8 14871.6 14940.3 15132.0 15159.3 301710.0 306627.0 307858.0 307899.0 308218.0 52818.5 52864.0 52889.2 52950.7 53016.5 1327.7 1330.6 1347.1 1350.9 1353.0 27032.3 27919.6 28099.2 28275.7 30916.5 48327.5 48723.8 48904.5 48949.5 48997.3 269290.0 271356.0 271983.0 272041.0 272306.0 27990.2 28482.9 30308.7 30915.7 31699.4 39246.0 39276.8 40054.2 40067.4 40271.6 35051.8 35505.3 36209.6 36404.3 36699.6 29680.4 34481.0 35627.6 36772.8 36938.6 32977.0 33234.5 33398.5 33443.0 34086.3 29761.3 30705.9 31019.2 31044.5 31085.7 23592.4 23692.8 24707.9 24923.6 25322.9 04:20:00 INFO - 2015-11-25 04:20:00,626 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,626 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27523.71 Median:3793.06 stddev:85185.01 (2245.8%) stddev-sans-first:85399.56 04:20:00 INFO - 2015-11-25 04:20:00,627 DEBUG : BROWSER_OUTPUT: Values: 358.4 359.2 360.6 360.8 362.2 14165.7 14208.5 16497.3 16736.7 17381.0 11213.4 11447.7 16721.9 17587.8 17603.2 4816.7 4902.8 4918.8 4923.2 4927.5 3742.4 3843.7 4888.6 4912.8 5366.7 5424.8 5453.1 5477.9 5483.0 5487.5 5370.9 5428.1 5908.8 5935.9 5943.0 5283.0 5286.9 5293.9 5297.1 5297.9 19760.5 20274.7 20525.2 21351.9 21407.9 2000.5 2022.5 2069.1 2069.2 2113.6 9425.9 9536.0 9746.7 9947.0 10043.9 1688.8 1841.4 1855.2 1868.1 1900.9 62190.7 63276.4 63436.0 63498.0 64129.4 9887.9 10173.4 10199.7 10236.4 10317.0 13544.8 13558.9 13577.6 13595.6 13599.5 7134.2 7203.5 7410.6 7470.9 7656.5 6715.7 6740.2 6750.9 6798.9 6803.5 4397.6 4427.4 4428.7 4441.6 4465.2 1709.0 1773.5 1779.9 1799.8 1855.7 417.2 800.2 887.0 907.9 933.9 10428.0 10684.5 10730.6 10983.7 11031.4 400.7 446.8 448.5 454.8 456.2 5684.9 5998.9 6111.4 6222.3 6227.9 499143.0 518383.0 525725.0 530652.0 531880.0 44238.6 72034.0 73012.5 77643.2 77884.0 1134.2 1158.3 1159.9 1163.2 1166.1 15419.0 15767.3 16140.6 16215.4 16356.5 34146.3 34369.0 35032.8 35639.0 36261.0 43299.1 44798.0 45338.3 45510.5 49399.8 9284.6 9340.5 9556.3 9565.4 9665.3 11496.7 11612.2 11667.3 11768.2 11773.2 13990.7 14511.3 14732.9 14830.4 15165.9 14563.3 14602.7 14628.7 14743.3 15186.5 11915.1 12213.6 13068.9 14969.6 15261.4 9475.7 9538.5 9694.2 9718.1 9726.3 10165.9 10275.8 13406.3 13991.0 14422.2 04:20:00 INFO - 2015-11-25 04:20:00,627 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,627 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5418.68 Median:6458.40 stddev:2806.47 (43.5%) stddev-sans-first:2798.81 04:20:00 INFO - 2015-11-25 04:20:00,627 DEBUG : BROWSER_OUTPUT: Values: 1490.3 1497.3 1545.7 1625.2 1699.0 6618.7 7480.7 7838.5 7861.8 7872.9 7661.8 7806.1 7828.7 7831.4 7837.8 2002.8 2034.2 2254.7 2292.3 2329.6 2305.0 2310.4 2312.7 2314.7 2316.8 2251.0 2276.0 2292.6 2298.3 2299.7 1999.8 2039.5 2214.1 2248.6 2253.1 2247.9 2278.0 2302.9 2314.9 2321.8 7791.4 7874.6 7893.9 7898.6 7904.0 4740.5 4798.2 4835.3 4840.8 4842.7 3089.8 3121.1 3162.2 3178.2 3278.1 1086.1 1119.2 1141.4 1799.1 1886.6 10510.9 10590.2 10604.4 10617.5 10619.1 4160.2 4220.2 4248.2 4272.7 4280.0 8641.6 8670.3 8682.9 8725.4 8738.8 4779.8 4781.8 4788.6 4803.3 4807.0 4387.0 4420.7 4421.3 4433.2 4435.4 3446.9 3485.2 3512.9 3520.3 3522.6 1053.9 1066.9 1070.4 1074.6 1077.9 971.6 995.9 996.6 997.1 1273.9 6329.9 7458.7 7685.8 7692.2 7693.4 575.8 576.8 577.7 587.4 595.1 5821.7 5857.6 5875.5 5891.1 5913.1 9302.4 9361.6 9379.4 9481.2 9496.8 9173.1 9260.1 9376.4 9383.3 9387.1 7076.4 7096.8 7160.0 7208.1 7216.8 6925.8 7046.2 7120.8 7122.9 7128.4 8078.5 8185.8 8229.8 8230.6 8306.7 8312.1 8315.6 8471.0 8491.8 8498.5 6523.5 6600.8 6613.5 6614.6 6619.5 7525.3 7526.1 7591.4 7601.4 7607.3 6756.4 7035.9 7113.4 7130.6 7170.6 6762.4 6947.0 6985.7 7013.3 7081.4 6321.9 6457.2 7043.5 7080.6 7099.4 6304.9 6433.2 6459.6 6491.9 6505.2 6516.4 6531.6 6567.9 6583.3 6593.3 04:20:00 INFO - 2015-11-25 04:20:00,627 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,628 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5389.86 Median:6039.32 stddev:1611.41 (26.7%) stddev-sans-first:1570.68 04:20:00 INFO - 2015-11-25 04:20:00,628 DEBUG : BROWSER_OUTPUT: Values: 337.8 345.0 347.4 353.1 356.3 5922.2 5957.9 5967.0 6007.2 6030.8 5696.2 5797.2 5812.1 5932.3 5956.9 4602.8 4628.9 4631.0 4666.3 4675.4 4613.8 4617.5 4635.0 4638.2 4664.6 4359.6 4432.1 4491.6 4578.5 4586.3 4617.2 4617.8 4630.6 4631.2 4641.7 4080.8 4082.3 4083.2 4087.9 4090.1 5699.1 5701.3 5702.6 5708.5 5712.0 5262.3 5464.3 5466.8 5474.7 5475.0 6397.1 6419.3 6435.4 6435.4 6438.7 4598.1 4600.6 4601.3 4606.5 4607.8 6370.4 6464.3 6489.5 6505.5 6533.8 4624.0 4631.0 4637.0 4637.1 4637.5 4687.1 4691.4 4692.6 4693.6 4696.8 5346.0 5346.1 5350.5 5354.5 5355.6 6282.5 6351.3 6444.2 6458.9 6476.2 1653.3 1689.3 1690.0 1690.3 1691.3 1126.6 1152.1 1153.1 1172.1 1174.0 6145.0 6150.8 6155.8 6162.4 6168.6 6591.4 6611.2 6618.5 6618.8 6627.7 5959.1 6047.9 6076.1 6198.7 6235.5 3587.1 3589.3 3600.6 3612.1 3613.7 6895.9 6896.7 6902.2 6907.5 6908.7 6684.2 6852.5 6865.2 6867.5 6876.7 6876.1 6901.9 6902.8 6903.4 6905.2 6592.6 6657.8 6663.0 6670.8 6677.5 6422.6 6617.5 6661.2 6670.2 6677.1 6928.8 6931.4 6933.8 6940.1 6945.3 4543.2 4577.4 4579.1 4580.5 4585.0 6595.1 6705.8 6720.5 6725.1 6726.5 6533.7 6540.7 6542.8 6543.5 6546.7 6352.0 6424.5 6441.0 6454.2 6458.1 6338.5 6402.3 6404.2 6404.9 6413.5 6549.3 6551.1 6555.7 6557.2 6562.2 6237.0 6371.4 6392.4 6395.1 6399.1 04:20:00 INFO - 2015-11-25 04:20:00,628 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-11-25 04:20:00,628 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13806.56 Median:50850.25 stddev:16249.16 (32.0%) stddev-sans-first:16265.46 04:20:00 INFO - 2015-11-25 04:20:00,628 DEBUG : BROWSER_OUTPUT: Values: 816.7 829.2 840.6 852.8 863.4 13693.2 14069.8 14082.1 14107.4 14122.6 13154.7 13248.9 13282.1 13313.8 13351.7 680.1 825.4 838.8 839.4 839.7 692.9 812.4 831.5 831.5 833.9 867.5 878.3 881.7 884.3 884.4 707.8 723.2 849.2 875.2 875.9 644.0 644.3 644.5 644.7 645.0 13506.7 13657.1 13663.6 13666.2 13677.3 12088.5 12561.4 12645.7 12693.9 12992.9 8833.5 9250.2 9408.9 9432.4 9519.9 3581.8 3644.5 3647.1 3654.3 3655.0 48025.3 48129.3 48132.8 48206.3 48253.3 8864.0 8976.1 9048.4 9236.6 9456.9 19031.2 19035.8 19057.1 19057.7 19059.0 7188.1 7206.6 7219.4 7337.3 7467.9 3721.5 3877.8 4089.4 4167.0 4192.9 2965.7 2986.8 3021.5 3024.0 3028.5 529.2 530.1 542.8 555.2 575.3 522.2 525.9 551.6 600.2 638.4 7854.3 8157.6 8272.7 8364.4 8440.1 549.0 591.9 611.8 672.5 687.2 3797.7 3803.6 3843.4 3972.2 4079.9 56716.0 56917.4 57214.1 57310.0 57701.0 50764.0 50936.5 51689.7 51993.2 52023.5 45051.3 46540.5 46603.8 46721.0 47744.5 7880.9 7923.6 8200.6 8314.5 8516.5 37725.4 38045.0 38668.0 38809.2 39509.0 45818.5 46021.3 46349.5 47041.8 47425.8 10228.5 10821.5 10998.9 11348.9 11388.0 10772.8 10960.5 10983.4 11030.2 11321.5 10768.9 11039.1 11057.9 11084.3 11143.7 10356.2 10782.1 10931.1 11169.9 11188.5 10065.3 10228.3 10232.2 10554.0 10569.3 9260.0 9540.8 9625.4 9647.2 9688.1 8755.8 8889.0 9074.9 9507.2 9571.7 04:20:00 INFO - 2015-11-25 04:20:00,628 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:20:00 INFO - 2015-11-25 04:20:00,630 DEBUG : BROWSER_OUTPUT: 04:20:01 INFO - 2015-11-25 04:20:01,377 INFO : Browser exited with error code: 0 04:20:01 INFO - 2015-11-25 04:20:01,398 INFO : Completed test dromaeo_css (00:18:34) 04:20:01 INFO - 2015-11-25 04:20:01,398 INFO : Starting test kraken 04:20:01 INFO - 2015-11-25 04:20:01,398 DEBUG : operating with platform_type : linux_ 04:20:01 INFO - 2015-11-25 04:20:01,398 INFO : Initialising browser for kraken test... 04:20:01 INFO - 2015-11-25 04:20:01,404 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphKuP6p/profile http://localhost:48298/getInfo.html 04:20:05 INFO - 2015-11-25 04:20:05,775 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:20:05 INFO - 2015-11-25 04:20:05,775 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:20:05 INFO - 2015-11-25 04:20:05,775 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:20:05 INFO - 2015-11-25 04:20:05,775 DEBUG : BROWSER_OUTPUT: __metrics 04:20:06 INFO - 2015-11-25 04:20:06,013 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:20:07 INFO - 2015-11-25 04:20:07,473 INFO : Browser initialized. 04:20:07 INFO - 2015-11-25 04:20:07,473 INFO : Running cycle 1/1 for kraken test... 04:20:07 INFO - 2015-11-25 04:20:07,473 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphKuP6p/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:20:19 INFO - 2015-11-25 04:20:19,307 DEBUG : BROWSER_OUTPUT: RSS: Main: 139014144 04:20:19 INFO - 2015-11-25 04:20:19,308 DEBUG : BROWSER_OUTPUT: 04:20:21 INFO - 2015-11-25 04:20:21,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48298/tests/kraken/driver.html?testName=audio-beat-detection) 04:20:22 INFO - 2015-11-25 04:20:22,355 DEBUG : BROWSER_OUTPUT: RSS: Main: 177229824 04:20:22 INFO - 2015-11-25 04:20:22,356 DEBUG : BROWSER_OUTPUT: 04:20:24 INFO - 2015-11-25 04:20:24,958 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48298/tests/kraken/driver.html?testName=audio-dft) 04:20:25 INFO - 2015-11-25 04:20:25,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 167370752 04:20:25 INFO - 2015-11-25 04:20:25,525 DEBUG : BROWSER_OUTPUT: 04:20:28 INFO - 2015-11-25 04:20:28,415 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48298/tests/kraken/driver.html?testName=audio-fft) 04:20:28 INFO - 2015-11-25 04:20:28,976 DEBUG : BROWSER_OUTPUT: RSS: Main: 150249472 04:20:28 INFO - 2015-11-25 04:20:28,976 DEBUG : BROWSER_OUTPUT: 04:20:30 INFO - 2015-11-25 04:20:30,968 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48298/tests/kraken/driver.html?testName=audio-oscillator) 04:20:31 INFO - 2015-11-25 04:20:31,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 153481216 04:20:31 INFO - 2015-11-25 04:20:31,535 DEBUG : BROWSER_OUTPUT: 04:20:33 INFO - 2015-11-25 04:20:33,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48298/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:20:34 INFO - 2015-11-25 04:20:34,277 DEBUG : BROWSER_OUTPUT: RSS: Main: 154329088 04:20:34 INFO - 2015-11-25 04:20:34,278 DEBUG : BROWSER_OUTPUT: 04:20:37 INFO - 2015-11-25 04:20:37,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48298/tests/kraken/driver.html?testName=imaging-darkroom) 04:20:37 INFO - 2015-11-25 04:20:37,739 DEBUG : BROWSER_OUTPUT: RSS: Main: 155320320 04:20:37 INFO - 2015-11-25 04:20:37,739 DEBUG : BROWSER_OUTPUT: 04:20:41 INFO - 2015-11-25 04:20:41,125 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48298/tests/kraken/driver.html?testName=imaging-desaturate) 04:20:41 INFO - 2015-11-25 04:20:41,698 DEBUG : BROWSER_OUTPUT: RSS: Main: 154157056 04:20:41 INFO - 2015-11-25 04:20:41,699 DEBUG : BROWSER_OUTPUT: 04:20:44 INFO - 2015-11-25 04:20:44,461 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48298/tests/kraken/driver.html?testName=json-parse-financial) 04:20:45 INFO - 2015-11-25 04:20:45,043 DEBUG : BROWSER_OUTPUT: RSS: Main: 155181056 04:20:45 INFO - 2015-11-25 04:20:45,043 DEBUG : BROWSER_OUTPUT: 04:20:46 INFO - 2015-11-25 04:20:46,591 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48298/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:47 INFO - 2015-11-25 04:20:47,157 DEBUG : BROWSER_OUTPUT: RSS: Main: 166109184 04:20:47 INFO - 2015-11-25 04:20:47,158 DEBUG : BROWSER_OUTPUT: 04:20:48 INFO - 2015-11-25 04:20:48,968 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:49 INFO - 2015-11-25 04:20:49,561 DEBUG : BROWSER_OUTPUT: RSS: Main: 164720640 04:20:49 INFO - 2015-11-25 04:20:49,561 DEBUG : BROWSER_OUTPUT: 04:20:51 INFO - 2015-11-25 04:20:51,597 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:52 INFO - 2015-11-25 04:20:52,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 168480768 04:20:52 INFO - 2015-11-25 04:20:52,168 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-11-25 04:20:54,562 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:20:55 INFO - 2015-11-25 04:20:55,143 DEBUG : BROWSER_OUTPUT: RSS: Main: 180109312 04:20:55 INFO - 2015-11-25 04:20:55,144 DEBUG : BROWSER_OUTPUT: 04:20:57 INFO - 2015-11-25 04:20:57,724 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:20:58 INFO - 2015-11-25 04:20:58,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 177737728 04:20:58 INFO - 2015-11-25 04:20:58,305 DEBUG : BROWSER_OUTPUT: 04:20:59 INFO - 2015-11-25 04:20:59,955 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48298/tests/kraken/driver.html?testName=ai-astar) 04:21:00 INFO - 2015-11-25 04:21:00,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 237350912 04:21:00 INFO - 2015-11-25 04:21:00,205 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,205 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:21:00 INFO - 2015-11-25 04:21:00,205 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:21:00 INFO - 2015-11-25 04:21:00,205 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:21:00 INFO - 2015-11-25 04:21:00,205 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;103;109;111;111;100;110;110;99 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;125;159;159;125;149;149;132;165;124 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;159;174;160;161;159;163;162;162 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;94;104;73;72;71;115;94;71 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;144;93;93;93;141;92;92;136 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;110;109;109;110;109 04:21:00 INFO - 2015-11-25 04:21:00,206 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;145;146;145;145;145;145 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;96;96;95;95;96;95;95 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;57;57;56;58;57;57;57;56 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;70;70;70;71;70;70;71;71 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;84;83;85;84;84;83;90;84 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;122;124;116;115;121;119;117;118;120 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;149;150;150;150;154;141;152;155;148;140 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;66;67;66;67;65;68;66;67 04:21:00 INFO - 2015-11-25 04:21:00,207 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:21:00 INFO - 2015-11-25 04:21:00,208 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:21:00 INFO - 2015-11-25 04:21:00,208 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,919 04:21:00 INFO - 2015-11-25 04:21:00,208 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:21:00 INFO - 2015-11-25 04:21:00,208 DEBUG : BROWSER_OUTPUT: __startTimestamp1448454060203__endTimestamp 04:21:00 INFO - 2015-11-25 04:21:00,208 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,210 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:21:00 INFO - 2015-11-25 04:21:00,211 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:21:00 INFO - 2015-11-25 04:21:00,211 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,211 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.40 Median:110.50 stddev:4.79 (4.3%) stddev-sans-first:4.99 04:21:00 INFO - 2015-11-25 04:21:00,212 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 103.0 109.0 111.0 111.0 100.0 110.0 110.0 99.0 04:21:00 INFO - 2015-11-25 04:21:00,212 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,212 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.90 Median:154.00 stddev:16.74 (10.9%) stddev-sans-first:16.58 04:21:00 INFO - 2015-11-25 04:21:00,212 DEBUG : BROWSER_OUTPUT: Values: 162.0 125.0 159.0 159.0 125.0 149.0 149.0 132.0 165.0 124.0 04:21:00 INFO - 2015-11-25 04:21:00,213 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,213 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.10 Median:161.50 stddev:4.38 (2.7%) stddev-sans-first:4.63 04:21:00 INFO - 2015-11-25 04:21:00,213 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 159.0 174.0 160.0 161.0 159.0 163.0 162.0 162.0 04:21:00 INFO - 2015-11-25 04:21:00,213 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,214 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:83.70 Median:72.00 stddev:16.59 (23.0%) stddev-sans-first:17.04 04:21:00 INFO - 2015-11-25 04:21:00,214 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 94.0 104.0 73.0 72.0 71.0 115.0 94.0 71.0 04:21:00 INFO - 2015-11-25 04:21:00,214 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,214 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.10 Median:93.00 stddev:23.02 (24.8%) stddev-sans-first:23.92 04:21:00 INFO - 2015-11-25 04:21:00,215 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 144.0 93.0 93.0 93.0 141.0 92.0 92.0 136.0 04:21:00 INFO - 2015-11-25 04:21:00,215 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,215 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 04:21:00 INFO - 2015-11-25 04:21:00,215 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 110.0 109.0 109.0 110.0 109.0 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 04:21:00 INFO - 2015-11-25 04:21:00,216 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 57.0 57.0 56.0 58.0 57.0 57.0 57.0 56.0 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 70.0 70.0 71.0 70.0 70.0 71.0 71.0 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:2.07 (2.5%) stddev-sans-first:2.13 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 84.0 83.0 85.0 84.0 84.0 83.0 90.0 84.0 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.60 Median:120.50 stddev:3.17 (2.6%) stddev-sans-first:2.93 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: Values: 124.0 122.0 124.0 116.0 115.0 121.0 119.0 117.0 118.0 120.0 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.90 Median:150.00 stddev:4.93 (3.3%) stddev-sans-first:5.23 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: Values: 149.0 150.0 150.0 150.0 154.0 141.0 152.0 155.0 148.0 140.0 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: 04:21:00 INFO - 2015-11-25 04:21:00,217 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:0.88 04:21:00 INFO - 2015-11-25 04:21:00,218 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 66.0 67.0 66.0 67.0 65.0 68.0 66.0 67.0 04:21:00 INFO - 2015-11-25 04:21:00,218 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:21:00 INFO - 2015-11-25 04:21:00,218 DEBUG : BROWSER_OUTPUT: 04:21:01 INFO - 2015-11-25 04:21:01,357 INFO : Browser exited with error code: 0 04:21:01 INFO - 2015-11-25 04:21:01,408 INFO : Completed test kraken (00:01:00) 04:21:01 INFO - 2015-11-25 04:21:01,408 INFO : Starting test v8_7 04:21:01 INFO - 2015-11-25 04:21:01,408 DEBUG : operating with platform_type : linux_ 04:21:01 INFO - 2015-11-25 04:21:01,408 INFO : Initialising browser for v8_7 test... 04:21:01 INFO - 2015-11-25 04:21:01,414 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpC0Ouj4/profile http://localhost:48298/getInfo.html 04:21:05 INFO - 2015-11-25 04:21:05,785 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:21:05 INFO - 2015-11-25 04:21:05,785 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:21:05 INFO - 2015-11-25 04:21:05,785 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:21:05 INFO - 2015-11-25 04:21:05,785 DEBUG : BROWSER_OUTPUT: __metrics 04:21:05 INFO - 2015-11-25 04:21:05,881 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:21:07 INFO - 2015-11-25 04:21:07,483 INFO : Browser initialized. 04:21:07 INFO - 2015-11-25 04:21:07,484 INFO : Running cycle 1/1 for v8_7 test... 04:21:07 INFO - 2015-11-25 04:21:07,484 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpC0Ouj4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:21:19 INFO - 2015-11-25 04:21:19,160 DEBUG : BROWSER_OUTPUT: RSS: Main: 138358784 04:21:19 INFO - 2015-11-25 04:21:19,161 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,585 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48298/tests/v8_7/run.html (next: http://localhost:48298/tests/v8_7/run.html) 04:21:40 INFO - 2015-11-25 04:21:40,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 620818432 04:21:40 INFO - 2015-11-25 04:21:40,835 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,835 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:21:40 INFO - 2015-11-25 04:21:40,835 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:21:40 INFO - 2015-11-25 04:21:40,835 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |0;Richards;164.77179106936893 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;196.61816751867872 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |2;Encrypt;285.46959748786753 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1114.8272017837235 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |5;Earley;783.0853563038371 04:21:40 INFO - 2015-11-25 04:21:40,836 DEBUG : BROWSER_OUTPUT: |6;Boyer;12023.809523809523 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: |7;RegExp;25641.02564102564 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: |8;Splay;674.6597537265068 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: __startTimestamp1448454100833__endTimestamp 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:21:40 INFO - 2015-11-25 04:21:40,837 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,838 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.77 Median:164.77 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,838 DEBUG : BROWSER_OUTPUT: Values: 164.8 04:21:40 INFO - 2015-11-25 04:21:40,838 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:196.62 Median:196.62 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: Values: 196.6 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:285.47 Median:285.47 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: Values: 285.5 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: Values: 5133.3 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1114.83 Median:1114.83 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: Values: 1114.8 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:783.09 Median:783.09 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,839 DEBUG : BROWSER_OUTPUT: Values: 783.1 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12023.81 Median:12023.81 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: Values: 12023.8 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25641.03 Median:25641.03 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: Values: 25641.0 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:674.66 Median:674.66 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: Values: 674.7 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: Values: 4826.9 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:21:40 INFO - 2015-11-25 04:21:40,840 DEBUG : BROWSER_OUTPUT: 04:21:41 INFO - 2015-11-25 04:21:41,669 INFO : Browser exited with error code: 0 04:21:41 INFO - 2015-11-25 04:21:41,681 INFO : Completed test v8_7 (00:00:40) 04:21:41 INFO - 2015-11-25 04:21:41,837 INFO : Completed test suite (00:20:15) 04:21:41 INFO - 2015-11-25 04:21:41,837 DEBUG : Working with test: dromaeo_css 04:21:41 INFO - 2015-11-25 04:21:41,837 DEBUG : Generating results file: dromaeo_css 04:21:41 INFO - 2015-11-25 04:21:41,838 DEBUG : Working with test: kraken 04:21:41 INFO - 2015-11-25 04:21:41,838 DEBUG : Generating results file: kraken 04:21:41 INFO - 2015-11-25 04:21:41,839 INFO : javascript benchmark 04:21:41 INFO - 2015-11-25 04:21:41,839 DEBUG : Working with test: v8_7 04:21:41 INFO - 2015-11-25 04:21:41,839 DEBUG : Generating results file: v8_7 04:21:41 INFO - 2015-11-25 04:21:41,839 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:21:41 INFO - 2015-11-25 04:21:41,981 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 04:21:41 INFO - 2015-11-25 04:21:41,981 DEBUG : process_Request line: dromaeo_css 6908.87 graph.html#tests=[[72,201,35]] 04:21:41 INFO - 2015-11-25 04:21:41,981 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:21:42 INFO - 2015-11-25 04:21:42,020 DEBUG : process_Request line: kraken 1501.60 graph.html#tests=[[232,201,35]] 04:21:42 INFO - 2015-11-25 04:21:42,020 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:21:42 INFO - 2015-11-25 04:21:42,053 DEBUG : process_Request line: v8_7 20774.39 graph.html#tests=[[230,201,35]] 04:21:42 INFO - 2015-11-25 04:21:42,058 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3192.8563214655337, 3214.1643172775102, 3249.610046794385, 3263.0374039658313, 3286.753493487988, 5124.410692770332, 5253.7373131343475, 5333.946660533394, 5362.249285100084, 5400.108982017972, 5273.0, 5351.57187425006, 5402.864928376793, 5445.101558242895, 5447.073997420438, 4909.8772530686665, 4916.090523253191, 4940.530649588283, 4945.085159245533, 4994.500549944994, 4747.90504189916, 4987.675801072919, 4988.600911927056, 5002.499750025005, 5071.239314102877, 4820.999999999983, 4823.614110871139, 4874.536918992725, 4938.555530002282, 4948.356713627241, 4925.827596034157, 4926.1625523660705, 4927.260910863399, 4938.0, 4968.378952630921, 4878.268259761029, 4881.609471242292, 4889.07107649549, 4890.070886531547, 4947.381577302837, 5093.236014597803, 5184.766685499161, 5204.583633309327, 5284.365876094855, 5318.680879147225, 5850.76596936122, 5854.5023672987845, 5855.06318988964, 5860.238169038041, 5865.677387743673, 5472.644278121909, 5861.85345366365, 5877.617954832965, 5883.558733094992, 5894.351621321651, 5413.133898576249, 5415.512603865671, 5434.103372943459, 5510.173473978935, 5512.5589952803675, 5911.349751527328, 5911.9113213301835, 5911.970440147772, 5919.082542205965, 5956.791512297049, 5728.083506638918, 5731.426857314319, 5739.225204597409, 5741.655500669973, 5750.7987220447085, 5621.381648018714, 5803.883922321615, 5805.36141024487, 5828.912566311508, 5944.405559444108, 5841.0, 5853.09277062056, 5857.033589457692, 5858.033424484997, 5863.970680146572, 5854.853628659318, 5858.50202732764, 5867.589268751231, 5873.618214816109, 5874.618149820248, 4373.103513779731, 4427.22523558376, 4442.8913738019555, 4501.212287849613, 4509.954900451019, 4019.1157945252, 4024.2353952748886, 4027.7381970171846, 4029.5567487576345, 4087.346024636044, 5522.2544956430365, 5536.557075433956, 5540.667559946335, 5543.196236545678, 5598.1322894951345, 5723.255976723, 5765.135229715576, 5828.766849326064, 5835.474807267281, 5887.558433117533, 5483.0, 5493.176023596413, 5494.752736126884, 5519.834404967857, 5524.613277070566, 5106.361704786902, 5117.078925793393, 5128.8717782055, 5131.3863179574, 5169.612279079084, 5722.570807189478, 5828.242328497353, 5828.59199856006, 5947.464728174485, 6037.215162028908, 5758.913616295843, 5769.278840144982, 5797.159411885337, 5797.855053623524, 5806.1000544915605, 5928.67392293428, 5962.07587823888, 5979.252593425822, 5980.671063091398, 5982.252218472691, 5598.244237027949, 5685.374608793112, 5729.312482502127, 5733.770649173986, 5734.598578099493, 5851.824445266649, 5887.0, 5919.378465261086, 5927.229460170151, 5941.702914854153, 5730.627509211888, 5746.655200687972, 5751.568632352507, 5752.6836024019085, 5754.798582049608, 5469.15228139639, 5560.499555039904, 5561.5272701820795, 5581.079121944834, 5675.545956323567, 5597.93639208549, 5617.606767526234, 5629.549636029026, 5631.577631677558, 5635.0702134147405, 5721.113227449752, 5755.654660720203, 5765.365809760839, 5770.480656740913, 5820.82537523875, 5425.348958125051, 5482.39693633708, 5541.113421852484, 5563.415841336763, 5583.74873130702], "unit": "ms", "name": "dojo.html", "value": 5354.673066225882}, {"lowerIsBetter": false, "replicates": [552.687731431741, 596.9701514924254, 601.4136217188242, 603.0682595390122, 603.7343568829716, 30731.539026914616, 39158.216835663305, 39522.209555808935, 39718.40422393666, 39779.40330895039, 41663.58336416635, 42141.36787948183, 42173.367399489034, 42229.15541689164, 42259.36610950838, 7582.128055273659, 7700.0, 8731.0, 8882.1117888211, 8889.555522223864, 10521.368717876914, 10787.730306742316, 10795.78408431831, 10797.244192906499, 10800.0, 10451.895481045169, 10560.155187585013, 10571.365718056903, 10604.78790424191, 10607.204459665518, 7801.0, 7975.7607271781935, 10413.687589372294, 10590.36457812526, 10893.782124357547, 10299.53652085658, 10387.792244155075, 10440.582376704922, 10444.321119127233, 10455.634052808191, 39226.21547569061, 39289.21421571581, 39641.60358396408, 39648.0, 39669.40495892564, 3070.723634872872, 3105.5652208690803, 3298.472244440878, 3511.613722490524, 3824.445455408978, 30282.84858575693, 30362.0, 30801.075967721004, 30816.075517734505, 30887.845560772275, 3925.7584416229665, 3936.6063393660693, 3937.1141493164096, 3976.681865450757, 4011.8796436106963, 271173.0, 271934.0, 273140.0, 273617.0, 274085.999999998, 56137.0, 57314.140287895716, 59073.40926590679, 59379.703101484214, 59441.70279148577, 68895.31104688889, 70153.64923175453, 70615.64692176609, 70697.64651176814, 70716.64641676759, 15037.849621503863, 15263.923680381527, 15281.694366112615, 15282.083075015535, 15291.388344466097, 11407.600733974437, 11469.139814513945, 11474.885251147381, 11478.483468243949, 11481.655550333504, 19344.32294869701, 19433.588894524022, 19443.23944294028, 19601.901990489958, 19784.0, 7549.811254718676, 7672.194419585975, 7735.961320193475, 7799.766007019798, 7820.648070836826, 922.6125027488471, 923.3490389275545, 925.8842644669417, 947.3131979314942, 947.8767760191131, 28436.857815710788, 28469.715302847122, 28504.71495285062, 28619.28451788722, 28955.42089158247, 1000.8798944126752, 1012.3217444312328, 1064.1539975719322, 1070.4112737994071, 1079.9190060745475, 14843.777343339858, 14871.553853384416, 14940.32768525419, 15132.0, 15159.317830697644, 301710.0, 306627.0, 307858.0, 307899.0, 308218.0, 52818.47181528212, 52864.0, 52889.206661900105, 52950.73524632352, 53016.46983530193, 1327.6680829792551, 1330.5808670268834, 1347.063790665478, 1350.8581598931971, 1352.9729405411836, 27032.32419189536, 27919.581206281517, 28099.157025289274, 28275.717242827308, 30916.53625195579, 48327.51672483371, 48723.75638121787, 48904.51095488999, 48949.51050489592, 48997.2650410237, 269290.0, 271356.0, 271983.0, 272041.0, 272306.0, 27990.160295191177, 28482.85758571194, 30308.696913030588, 30915.69084309218, 31699.366012679155, 39246.0, 39276.803615981735, 40054.1989160221, 40067.39898901577, 40271.59728402796, 35051.82474087613, 35505.28989420249, 36209.63790362168, 36404.27191456209, 36699.63300366962, 29680.406391872475, 34481.0, 35627.64372356243, 36772.81613591915, 36938.63061369459, 32977.0, 33234.50148247826, 33398.49902251517, 33443.0, 34086.31827363488, 29761.25596860096, 30705.92529261465, 31019.224519386295, 31044.53433198549, 31085.68914310828, 23592.410189745395, 23692.763072369744, 24707.876460617583, 24923.626145608192, 25322.873385632953], "unit": "ms", "name": "ext.html", "value": 18631.949637366113}, {"lowerIsBetter": false, "replicates": [358.3674813953372, 359.2312451354102, 360.63395653411794, 360.78713559000187, 362.18508356198555, 14165.71668566628, 14208.502702405418, 16497.340106395746, 16736.665266694657, 17381.0, 11213.439328033586, 11447.713807154805, 16721.91639041803, 17587.82412175878, 17603.207855646495, 4816.710997340162, 4902.803887844482, 4918.827841025565, 4923.18767403378, 4927.457979622239, 3742.3825068863603, 3843.730938834283, 4888.560029597335, 4912.803487860481, 5366.677999320033, 5424.83725488236, 5453.127499600065, 5477.890442191154, 5483.0, 5487.533559647415, 5370.91943620846, 5428.077226871442, 5908.822735317947, 5935.9406405935815, 5942.970285148546, 5283.022640811462, 5286.867828304285, 5293.86765330866, 5297.099493086184, 5297.894042119175, 19760.505987350287, 20274.69587956182, 20525.17899284027, 21351.893240533853, 21407.892960535097, 2000.4998750312423, 2022.464047027539, 2069.1206237349065, 2069.2343832781967, 2113.6406810842195, 9425.905740942571, 9535.952320238424, 9746.658866939622, 9946.950265248628, 10043.949780251125, 1688.7804585403942, 1841.4291569613329, 1855.239351865742, 1868.0753027251462, 1900.914458849355, 62190.68904655448, 63276.36723632797, 63436.0, 63497.99999999954, 64129.35870641281, 9887.851682224773, 10173.44046077458, 10199.694009179737, 10236.385816850863, 10317.0, 13544.796828047587, 13558.932205339108, 13577.59267221985, 13595.592132236048, 13599.52401665937, 7134.180783900176, 7203.459740519482, 7410.592417416986, 7470.887936680954, 7656.502327348816, 6715.664216789141, 6740.157480314961, 6750.932490675129, 6798.898016529756, 6803.48973826965, 4397.6042156206095, 4427.446569178853, 4428.667849911205, 4441.644668426583, 4465.151621191963, 1709.0429359558686, 1773.4502304285713, 1779.8576113910856, 1799.8110198429238, 1855.6752568300763, 417.19689597524643, 800.2237829305565, 886.9645214191489, 907.9182873541413, 933.8739270198572, 10428.0, 10684.46577671129, 10730.624428144978, 10983.670489885317, 11031.393273369882, 400.71949635255436, 446.8471343932646, 448.49544262704455, 454.8135264541588, 456.21303251890885, 5684.886302274014, 5998.880022399528, 6111.449969502677, 6222.315545290105, 6227.937720622826, 499143.0, 518383.0, 525725.0, 530652.0, 531880.0, 44238.557614423444, 72034.0, 73012.52406069566, 77643.2235677636, 77884.0, 1134.183387960667, 1158.3050169897995, 1159.8550181227347, 1163.1625229834674, 1166.0787977497682, 15419.0, 15767.290471928562, 16140.596485087966, 16215.351385944901, 16356.509304720877, 34146.31707365889, 34369.0, 35032.82483587566, 35639.0, 36261.0, 43299.13401732011, 44798.0, 45338.31992520181, 45510.54489455063, 49399.75300123476, 9284.62861485533, 9340.53297335144, 9556.331056826233, 9565.426074435556, 9665.3234273603, 11496.655100347003, 11612.245204061708, 11667.299962002307, 11768.235064720766, 11773.17587768848, 13990.720185596434, 14511.274436277932, 14732.926335368684, 14830.40678372853, 15165.924170379078, 14563.271836408348, 14602.707945840812, 14628.707425851637, 14743.336549855068, 15186.544403667465, 11915.106367022334, 12213.572524961939, 13068.869311306766, 14969.550913472613, 15261.389544418544, 9475.715728528156, 9538.523073846141, 9694.175995040501, 9718.076782705632, 9726.270529710158, 10165.898341016495, 10275.794484110425, 13406.32968351598, 13991.0, 14422.206778626856], "unit": "ms", "name": "jquery.html", "value": 8193.345105357475}, {"lowerIsBetter": false, "replicates": [1490.3219035338923, 1497.348653335799, 1545.714042902064, 1625.1549194418903, 1698.991505042475, 6618.669066546671, 7480.663370148342, 7838.451308408399, 7861.842763144734, 7872.8819067713885, 7661.770146895588, 7806.14132445432, 7828.725994590191, 7831.373490120791, 7837.764867053983, 2002.7997200280001, 2034.216826521785, 2254.6730724045005, 2292.3123063081093, 2329.5690297295055, 2304.9654255186188, 2310.387747246981, 2312.710911136108, 2314.652802079693, 2316.791488766011, 2251.0, 2275.988620056906, 2292.59879521084, 2298.2645553422826, 2299.6895419118373, 1999.8400127989798, 2039.4799326171806, 2214.0700905619597, 2248.6289762189217, 2253.121282699732, 2247.9438014049615, 2278.0, 2302.861828290308, 2314.93055208344, 2321.756215597372, 7791.376689864797, 7874.5668988205625, 7893.881591776128, 7898.60506974649, 7904.0, 4740.454847692525, 4798.160321943646, 4835.347228124193, 4840.806367745286, 4842.733649649268, 3089.830059346735, 3121.0636808957447, 3162.193640621627, 3178.1895616617617, 3278.065751260879, 1086.0659832544006, 1119.2053641914251, 1141.4406940599129, 1799.127423199747, 1886.5755205078747, 10510.894891051068, 10590.205734569938, 10604.416757078357, 10617.469126543641, 10619.097376722988, 4160.209560183555, 4220.2192594370135, 4248.171606536757, 4272.700910936204, 4280.036991676848, 8641.611127499278, 8670.349723770742, 8682.86975695365, 8725.432846864933, 8738.781530461789, 4779.761011949388, 4781.832635857728, 4788.592969597623, 4803.327534145223, 4807.038592281488, 4387.034852332482, 4420.668449866273, 4421.336799480104, 4433.179861725526, 4435.356873253425, 3446.913827154341, 3485.1809824691177, 3512.9297414051575, 3520.331137083946, 3522.559680039995, 1053.9367637941748, 1066.8666416697913, 1070.3845288958723, 1074.5540600650643, 1077.9407132607625, 971.5676523946917, 995.9302848800514, 996.6163027234422, 997.0976266478848, 1273.8598754137038, 6329.873402531924, 7458.738944137038, 7685.769426917202, 7692.19231980634, 7693.384529237647, 575.7725698349128, 576.7952376906222, 577.6707276852238, 587.4419301663437, 595.0776296740036, 5821.708914554255, 5857.648541087548, 5875.471207591338, 5891.05743081105, 5913.083472061838, 9302.441853488812, 9361.62553497866, 9379.437233765995, 9481.194098501566, 9496.810063798684, 9173.082691730908, 9260.073992600821, 9376.437413755197, 9383.296252780932, 9387.10822471864, 7076.363127318566, 7096.787096387014, 7160.0, 7208.135023797073, 7216.819579510554, 6925.757598484029, 7046.2249152594195, 7120.821979450555, 7122.928770712227, 7128.429725622042, 8078.515289082674, 8185.754427367188, 8229.753107406787, 8230.588470576567, 8306.667733290842, 8312.08567057611, 8315.584220789058, 8470.957645211734, 8491.830163396575, 8498.53258070812, 6523.543351965317, 6600.768973085919, 6613.5370524062855, 6614.570052946543, 6619.50353723463, 7525.3227209551405, 7526.134494533198, 7591.392688585011, 7601.429892758176, 7607.315341619281, 6756.391924726799, 7035.929640703527, 7113.359797618033, 7130.607816570138, 7170.605616691132, 6762.391384775394, 6947.0, 6985.685644145924, 7013.263607321158, 7081.362677358857, 6321.873562528632, 6457.192850893638, 7043.542169758949, 7080.610566418896, 7099.396551292992, 6304.905426418516, 6433.195850518685, 6459.61242325462, 6491.870162596816, 6505.21937367519, 6516.413522782972, 6531.575447596081, 6567.868642627025, 6583.30875258091, 6593.307702691149], "unit": "ms", "name": "mootools.html", "value": 4407.651580003744}, {"lowerIsBetter": false, "replicates": [337.7720038973693, 344.9896503104906, 347.40073373430835, 353.12425185539877, 356.3176516970001, 5922.2301100856885, 5957.940420595798, 5966.9557827380195, 6007.15899774032, 6030.75876964922, 5696.202531645563, 5797.246357973458, 5812.1281807728865, 5932.288125424944, 5956.910646340309, 4602.81588736451, 4628.930566041512, 4630.97684511577, 4666.2767271073035, 4675.392199014123, 4613.769311534426, 4617.538246175389, 4634.953650463486, 4638.188317044521, 4664.55686709762, 4359.607635312821, 4432.135733531963, 4491.595756381925, 4578.519255478177, 4586.312053192031, 4617.191991401492, 4617.838375656869, 4630.560096790817, 4631.189541830183, 4641.675082744209, 4080.755154690728, 4082.265192265391, 4083.1629515949458, 4087.9386809197886, 4090.141070375225, 5699.088145896637, 5701.315842098975, 5702.600817942745, 5708.543316534668, 5712.0, 5262.315898933152, 5464.3442786865435, 5466.808661696821, 5474.7262636868, 5475.014497390468, 6397.1363865877975, 6419.26178489475, 6435.388638079375, 6435.420812126931, 6438.742450301982, 4598.126355992351, 4600.608948239404, 4601.28680054592, 4606.493285738566, 4607.815687372501, 6370.394812492806, 6464.256610489807, 6489.545731798776, 6505.4795616350575, 6533.771318003847, 4624.0, 4630.9536904631195, 4636.976815115903, 4637.095766325601, 4637.536246375335, 4687.109449204709, 4691.366665500113, 4692.554207350297, 4693.601043911238, 4696.7651617419, 5346.0, 5346.064438723208, 5350.491703288181, 5354.544863686552, 5355.571554275649, 6282.4974002080635, 6351.333110023474, 6444.226692796894, 6458.869697802959, 6476.158099447041, 1653.3469279634478, 1689.2736123467027, 1689.991550042242, 1690.3492155520214, 1691.3150174179477, 1126.5662719852917, 1152.1071169843271, 1153.0832987774738, 1172.0740614914293, 1173.988260117405, 6145.0475176346845, 6150.7539698411565, 6155.815325540241, 6162.445379915829, 6168.568200225941, 6591.373819487141, 6611.206655201407, 6618.4705223582, 6618.8345291368105, 6627.668616569153, 5959.0763431668165, 6047.879042419127, 6076.058210977306, 6198.659073750987, 6235.469985051322, 3587.0673624857723, 3589.335972845032, 3600.6399360063783, 3612.1150318172217, 3613.674769270778, 6895.862082758417, 6896.689648965808, 6902.171739391305, 6907.516473846783, 6908.723651054002, 6684.16447944007, 6852.451803855779, 6865.210500792373, 6867.450603951672, 6876.724931002704, 6876.140482439695, 6901.861962760717, 6902.758403455855, 6903.447724182053, 6905.1713794344, 6592.604443733391, 6657.766978155935, 6662.966685166543, 6670.83322916931, 6677.465802735866, 6422.550421470639, 6617.536772425884, 6661.233958094881, 6670.199576050905, 6677.09859169006, 6928.757493487704, 6931.410830079298, 6933.826654333682, 6940.13248343957, 6945.340192681687, 4543.227651299353, 4577.35215322477, 4579.129965306581, 4580.519045500175, 4585.037142200042, 6595.076689263603, 6705.832354191184, 6720.46236301085, 6725.125733654593, 6726.461883049246, 6533.705983230861, 6540.672966351568, 6542.803715888531, 6543.5419520633095, 6546.672666366758, 6351.96824015877, 6424.51816113784, 6441.033844923299, 6454.19322584677, 6458.095866578588, 6338.461230795249, 6402.295747467681, 6404.1674582304, 6404.871902562016, 6413.486921046212, 6549.345065493603, 6551.082848401323, 6555.737770489127, 6557.2131344239, 6562.179727534059, 6237.0, 6371.39471750183, 6392.360763923756, 6395.072714456284, 6399.104125422352], "unit": "ms", "name": "prototype.html", "value": 4873.991940364103}, {"lowerIsBetter": false, "replicates": [816.7427260412966, 829.2412442615008, 840.5755093677692, 852.7995920958574, 863.438764802878, 13693.24687142208, 14069.788953165698, 14082.0846644968, 14107.365168567414, 14122.576322710322, 13154.671133221675, 13248.867511324885, 13282.070255082148, 13313.80029299559, 13351.666208344795, 680.1124532485105, 825.4304529874397, 838.8322335532887, 839.4040231435657, 839.7102999465175, 692.9341712537301, 812.3947658994058, 831.4678605692362, 831.4969443486694, 833.9291160251386, 867.4708427859033, 878.2578720980761, 881.6958149438466, 884.3411658314565, 884.3986089459165, 707.8478127202676, 723.1683563901503, 849.1975083546051, 875.1991927386434, 875.8905136857893, 644.0339490763854, 644.3202421445374, 644.5423749138117, 644.716324817079, 645.0195702532125, 13506.662333441644, 13657.112287701368, 13663.590092297247, 13666.18002919828, 13677.31613419335, 12088.456019479145, 12561.434735436836, 12645.683857903534, 12693.873061269362, 12992.935035324857, 8833.469991800448, 9250.213731832735, 9408.858867116998, 9432.434053956717, 9519.904800951972, 3581.7850928944345, 3644.5262115925025, 3647.1429214134655, 3654.28741395428, 3655.0496870813513, 48025.27962080572, 48129.278060829114, 48132.759336203446, 48206.27690584644, 48253.27620085701, 8864.0, 8976.102389760972, 9048.366614336934, 9236.5843537041, 9456.858147127661, 19031.238750449826, 19035.809641903405, 19057.142428590745, 19057.714134287995, 19059.0, 7188.065551478379, 7206.639668016578, 7219.386352160125, 7337.302956219258, 7467.887981680279, 3721.5191135347754, 3877.7867217302755, 4089.4070359798257, 4167.0, 4192.874213773592, 2965.7182567656037, 2986.8357240351556, 3021.4561378952, 3023.9567349264466, 3028.454878121915, 529.2061907139291, 530.082956485275, 542.7638977044998, 555.1506195520859, 575.3441077172027, 522.1593234891822, 525.90796610593, 551.5918220516788, 600.2466904035408, 638.3839594790957, 7854.253845884632, 8157.592120394075, 8272.710455134162, 8364.37267204962, 8440.071592124861, 548.9807856724996, 591.8905002574537, 611.8470382404399, 672.5292295393147, 687.189116842131, 3797.734158608871, 3803.6196380362294, 3843.442700808423, 3972.205504185644, 4079.938800917989, 56716.0, 56917.43082569204, 57214.14178787322, 57310.0, 57701.0, 50764.0, 50936.49063509391, 51689.741551292005, 51993.220101698505, 52023.47976520186, 45051.324230136575, 46540.534594654295, 46603.76698116556, 46721.0, 47744.5225547747, 7880.881786773318, 7923.603819809102, 8200.58997050157, 8314.459560128573, 8516.489010659381, 37725.4341184877, 38045.0, 38668.0, 38809.223815522964, 39509.0, 45818.541814582764, 46021.30968035415, 46349.536504634525, 47041.76479117719, 47425.76287118543, 10228.532953105067, 10821.458927053458, 10998.945005275242, 11348.86402035429, 11388.032017278623, 10772.838407423738, 10960.506777195205, 10983.39591322485, 11030.172737044593, 11321.547138114713, 10768.892311076788, 11039.116870650168, 11057.944710276397, 11084.334939903632, 11143.72140696489, 10356.171506279328, 10782.08352290064, 10931.125509959025, 11169.888301116885, 11188.496517656888, 10065.295429319878, 10228.335158214692, 10232.23258255619, 10554.0, 10569.260151789302, 9260.0, 9540.809183816424, 9625.422474651543, 9647.228221742384, 9688.079632434907, 8755.824883502168, 8888.955555222183, 9074.909250907407, 9507.191888689262, 9571.664991725534], "unit": "ms", "name": "yui.html", "value": 6192.420911504998}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 103.0, 109.0, 111.0, 111.0, 100.0, 110.0, 110.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 107.4}, {"lowerIsBetter": true, "replicates": [162.0, 125.0, 159.0, 159.0, 125.0, 149.0, 149.0, 132.0, 165.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.9}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 159.0, 174.0, 160.0, 161.0, 159.0, 163.0, 162.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 162.1}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 94.0, 104.0, 73.0, 72.0, 71.0, 115.0, 94.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 83.7}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 144.0, 93.0, 93.0, 93.0, 141.0, 92.0, 92.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 107.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 57.0, 57.0, 56.0, 58.0, 57.0, 57.0, 57.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 56.9}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 70.0, 70.0, 71.0, 70.0, 70.0, 71.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.3}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 84.0, 83.0, 85.0, 84.0, 84.0, 83.0, 90.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.4}, {"lowerIsBetter": true, "replicates": [124.0, 122.0, 124.0, 116.0, 115.0, 121.0, 119.0, 117.0, 118.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.6}, {"lowerIsBetter": true, "replicates": [149.0, 150.0, 150.0, 150.0, 154.0, 141.0, 152.0, 155.0, 148.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.9}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 67.0, 65.0, 68.0, 66.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.3}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.77179106936893], "unit": "score", "name": "Richards", "value": 214.24783800000003}, {"lowerIsBetter": false, "replicates": [196.61816751867872], "unit": "score", "name": "DeltaBlue", "value": 336.276148}, {"lowerIsBetter": false, "replicates": [285.46959748786753], "unit": "score", "name": "Encrypt", "value": 932.4320429999997}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [1114.8272017837235], "unit": "score", "name": "RayTrace", "value": 663.7701329999999}, {"lowerIsBetter": false, "replicates": [783.0853563038371], "unit": "score", "name": "Earley", "value": 851.0732510000003}, {"lowerIsBetter": false, "replicates": [12023.809523809523], "unit": "score", "name": "Boyer", "value": 55.42860594059401}, {"lowerIsBetter": false, "replicates": [25641.02564102564], "unit": "score", "name": "RegExp", "value": 35.52841499999999}, {"lowerIsBetter": false, "replicates": [674.6597537265068], "unit": "score", "name": "Splay", "value": 120.78829298751195}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7"}]} 04:21:42 INFO - RETURN: dromaeo_css: 6908.87 04:21:42 INFO - RETURN: kraken: 1501.60 04:21:42 INFO - RETURN: v8_7: 20774.39 04:21:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "20774.39"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1501.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6908.87"}}} 04:21:42 INFO - Return code: 0 04:21:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 04:21:42 INFO - # TBPL SUCCESS # 04:21:42 INFO - Running post-action listener: _resource_record_post_action 04:21:42 INFO - Running post-run listener: _resource_record_post_run 04:21:43 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 4096; Write bytes: 523395072; Read time: 4; Write time: 967188 04:21:43 INFO - install - Wall time: 15s; CPU: 19.0%; Read bytes: 0; Write bytes: 247898112; Read time: 0; Write time: 703148 04:21:43 INFO - run-tests - Wall time: 1216s; CPU: 13.0%; Read bytes: 4096; Write bytes: 275496960; Read time: 4; Write time: 264040 04:21:43 INFO - Running post-run listener: _upload_blobber_files 04:21:43 INFO - Blob upload gear active. 04:21:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:21:43 INFO - Copying logs to upload dir... 04:21:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1263.781054 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2015-11-25 04:21:43.052085) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 04:21:43.055668) ========= 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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010082 build_url: 'https://queue.taskcluster.net/v1/task/U0Sh7VS7Rbe8d7i87uBkAg/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-25 04:21:43.107880) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:21:43.108339) ========= 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=c402a24d74c70935940d60dd000001de-1448451403.44458-548766397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 04:21:43.158004) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 04:21:43.158335) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 04:21:43.158643) ========= ========= Total master_lag: 0.23 =========