builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1448423321.63 results: success (0) buildid: 20151124173007 builduid: b9a0b435a9ea4474b08f877db04c39b3 revision: dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.631333) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.631821) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.632135) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.007783 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.656152) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.656485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.670509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.670895) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004711 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.719923) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.720235) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.720662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.720953) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 19:48:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 73.0M=0s 2015-11-24 19:48:42 (73.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.201975 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.935563) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.935880) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016007 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.985955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:41.986332) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23 --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 dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23 --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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 19:48:42,358 truncating revision to first 12 chars 2015-11-24 19:48:42,358 Setting DEBUG logging. 2015-11-24 19:48:42,358 attempt 1/10 2015-11-24 19:48:42,358 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/dbf3d11d0f9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 19:48:42,796 unpacking tar archive at: b2g-inbound-dbf3d11d0f9e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.723524 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:42.723160) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:42.723522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:42.735088) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:42.735396) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 19:48:42.735916) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2015-11-24 19:48:42.736211) ========= /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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:48:43 INFO - MultiFileLogger online at 20151124 19:48:43 in /builds/slave/test-pgo 19:48:43 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 19:48:43 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:48:43 INFO - {'append_to_log': False, 19:48:43 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:48:43 INFO - 'blob_upload_branch': 'B2G-Inbound', 19:48:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:48:43 INFO - 'branch': 'B2G-Inbound', 19:48:43 INFO - 'buildbot_json_path': 'buildprops.json', 19:48:43 INFO - 'config_files': ('talos/linux_config.py',), 19:48:43 INFO - 'default_actions': ('clobber', 19:48:43 INFO - 'read-buildbot-config', 19:48:43 INFO - 'download-and-extract', 19:48:43 INFO - 'populate-webroot', 19:48:43 INFO - 'create-virtualenv', 19:48:43 INFO - 'install', 19:48:43 INFO - 'run-tests'), 19:48:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:48:43 INFO - 'download_minidump_stackwalk': True, 19:48:43 INFO - 'download_symbols': 'ondemand', 19:48:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:48:43 INFO - 'tooltool.py': '/tools/tooltool.py', 19:48:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:48:43 INFO - '/tools/misc-python/virtualenv.py')}, 19:48:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:48:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:48:43 INFO - 'installer_path': 'installer.exe', 19:48:43 INFO - 'log_level': 'info', 19:48:43 INFO - 'log_name': 'talos', 19:48:43 INFO - 'log_to_console': True, 19:48:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:48:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:48:43 INFO - 'opt_config_files': (), 19:48:43 INFO - 'pip_index': False, 19:48:43 INFO - 'sps_profile': False, 19:48:43 INFO - 'sps_profile_interval': 0, 19:48:43 INFO - 'suite': 'dromaeojs', 19:48:43 INFO - 'system_bits': '64', 19:48:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:48:43 INFO - 'title': 'talos-linux64-ix-009', 19:48:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:48:43 INFO - 'use_talos_json': True, 19:48:43 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:48:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:48:43 INFO - 'work_dir': 'build'} 19:48:43 INFO - ##### 19:48:43 INFO - ##### Running clobber step. 19:48:43 INFO - ##### 19:48:43 INFO - Running pre-action listener: _resource_record_pre_action 19:48:43 INFO - Running main action method: clobber 19:48:43 INFO - rmtree: /builds/slave/test-pgo/build 19:48:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:48:43 INFO - Running post-action listener: _resource_record_post_action 19:48:43 INFO - ##### 19:48:43 INFO - ##### Running read-buildbot-config step. 19:48:43 INFO - ##### 19:48:43 INFO - Running pre-action listener: _resource_record_pre_action 19:48:43 INFO - Running main action method: read_buildbot_config 19:48:43 INFO - Using buildbot properties: 19:48:43 INFO - { 19:48:43 INFO - "properties": { 19:48:43 INFO - "buildnumber": 1003, 19:48:43 INFO - "product": "firefox", 19:48:43 INFO - "script_repo_revision": "production", 19:48:43 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 19:48:43 INFO - "repository": "", 19:48:43 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 19:48:43 INFO - "buildid": "20151124173007", 19:48:43 INFO - "slavename": "talos-linux64-ix-009", 19:48:43 INFO - "pgo_build": "True", 19:48:43 INFO - "basedir": "/builds/slave/test-pgo", 19:48:43 INFO - "project": "", 19:48:43 INFO - "platform": "ubuntu64_hw", 19:48:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:48:43 INFO - "slavebuilddir": "test", 19:48:43 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 19:48:43 INFO - "branch": "b2g-inbound", 19:48:43 INFO - "repo_path": "integration/b2g-inbound", 19:48:43 INFO - "stage_platform": "linux64-pgo", 19:48:43 INFO - "builduid": "b9a0b435a9ea4474b08f877db04c39b3", 19:48:43 INFO - "revision": "dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23" 19:48:43 INFO - }, 19:48:43 INFO - "sourcestamp": { 19:48:43 INFO - "repository": "", 19:48:43 INFO - "hasPatch": false, 19:48:43 INFO - "project": "", 19:48:43 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 19:48:43 INFO - "changes": [ 19:48:43 INFO - { 19:48:43 INFO - "category": null, 19:48:43 INFO - "files": [ 19:48:43 INFO - { 19:48:43 INFO - "url": null, 19:48:43 INFO - "name": "https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 19:48:43 INFO - } 19:48:43 INFO - ], 19:48:43 INFO - "repository": "", 19:48:43 INFO - "rev": "dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23", 19:48:43 INFO - "who": "sendchange", 19:48:43 INFO - "when": 1448423287, 19:48:43 INFO - "number": 6703565, 19:48:43 INFO - "comments": "", 19:48:43 INFO - "project": "", 19:48:43 INFO - "at": "Tue 24 Nov 2015 19:48:07", 19:48:43 INFO - "branch": "b2g-inbound-linux64-pgo-talos", 19:48:43 INFO - "revlink": "", 19:48:43 INFO - "properties": [ 19:48:43 INFO - [ 19:48:43 INFO - "buildid", 19:48:43 INFO - "20151124173007", 19:48:43 INFO - "Change" 19:48:43 INFO - ], 19:48:43 INFO - [ 19:48:43 INFO - "builduid", 19:48:43 INFO - "b9a0b435a9ea4474b08f877db04c39b3", 19:48:43 INFO - "Change" 19:48:43 INFO - ], 19:48:43 INFO - [ 19:48:43 INFO - "pgo_build", 19:48:43 INFO - "True", 19:48:43 INFO - "Change" 19:48:43 INFO - ] 19:48:43 INFO - ], 19:48:43 INFO - "revision": "dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23" 19:48:43 INFO - } 19:48:43 INFO - ], 19:48:43 INFO - "revision": "dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23" 19:48:43 INFO - } 19:48:43 INFO - } 19:48:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 19:48:43 INFO - Running post-action listener: _resource_record_post_action 19:48:43 INFO - ##### 19:48:43 INFO - ##### Running download-and-extract step. 19:48:43 INFO - ##### 19:48:43 INFO - Running pre-action listener: _resource_record_pre_action 19:48:43 INFO - Running main action method: download_and_extract 19:48:43 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:48:43 INFO - https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 19:48:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/test_packages.json 19:48:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/test_packages.json 19:48:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:48:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:48:44 INFO - Downloaded 1302 bytes. 19:48:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:48:44 INFO - Using the following test package requirements: 19:48:44 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:48:44 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:48:44 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'jsshell-linux-x86_64.zip'], 19:48:44 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:48:44 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:48:44 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:48:44 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:48:44 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:48:44 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 19:48:44 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 19:48:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:48:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:48: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')]} 19:48:44 INFO - https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:48:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:48:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:48:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:46 INFO - Downloaded 22530009 bytes. 19:48: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 19:48:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:48: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 19:48:47 INFO - Return code: 0 19:48:47 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:48: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')]} 19:48:47 INFO - https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:48:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:48:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:48:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:48 INFO - Downloaded 22530009 bytes. 19:48:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 19:48:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 19:48:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 19:48:49 INFO - Return code: 0 19:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:48:49 INFO - https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:48:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:48:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:48:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:51 INFO - Downloaded 11131717 bytes. 19:48:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 19:48:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 19:48:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 19:48:51 INFO - Return code: 0 19:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:48:51 INFO - https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:48:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:48:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:48:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/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 19:48:53 INFO - Downloaded 58386602 bytes. 19:48:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:48:53 INFO - mkdir: /builds/slave/test-pgo/properties 19:48:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:48:53 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:48:53 INFO - Contents: 19:48:53 INFO - build_url:https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 19:48:53 INFO - Running post-action listener: _resource_record_post_action 19:48:53 INFO - Running post-action listener: set_extra_try_arguments 19:48:53 INFO - ##### 19:48:53 INFO - ##### Running populate-webroot step. 19:48:53 INFO - ##### 19:48:53 INFO - Running pre-action listener: _resource_record_pre_action 19:48:53 INFO - Running main action method: populate_webroot 19:48:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:48:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:48:53 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 19:48:53 INFO - '../fennec_ids.txt'], 19:48:53 INFO - 'tests': ['tcheck2']}, 19:48:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:48:53 INFO - '--tppagecycles', 19:48:53 INFO - '7'], 19:48:53 INFO - 'tests': ['tsvgm']}}, 19:48:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:48:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:48:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:48:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:48:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:48:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:48:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'tests': ['damp', 'tps']}, 19:48:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['damp', 'tps']}, 19:48:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:48:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:48:53 INFO - 'other': {'tests': ['a11yr', 19:48:53 INFO - 'ts_paint', 19:48:53 INFO - 'tpaint', 19:48:53 INFO - 'sessionrestore', 19:48:53 INFO - 'sessionrestore_no_auto_restore']}, 19:48:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['a11yr', 19:48:53 INFO - 'ts_paint', 19:48:53 INFO - 'tpaint', 19:48:53 INFO - 'sessionrestore', 19:48:53 INFO - 'sessionrestore_no_auto_restore']}, 19:48:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['a11yr', 19:48:53 INFO - 'ts_paint', 19:48:53 INFO - 'tpaint', 19:48:53 INFO - 'sessionrestore', 19:48:53 INFO - 'sessionrestore_no_auto_restore']}, 19:48:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['a11yr', 19:48:53 INFO - 'ts_paint', 19:48:53 INFO - 'tpaint', 19:48:53 INFO - 'sessionrestore', 19:48:53 INFO - 'sessionrestore_no_auto_restore']}, 19:48:53 INFO - 'other_l64': {'tests': ['a11yr', 19:48:53 INFO - 'ts_paint', 19:48:53 INFO - 'tpaint', 19:48:53 INFO - 'sessionrestore', 19:48:53 INFO - 'sessionrestore_no_auto_restore']}, 19:48:53 INFO - 'other_nol64': {'tests': ['a11yr', 19:48:53 INFO - 'ts_paint', 19:48:53 INFO - 'tpaint', 19:48:53 INFO - 'sessionrestore', 19:48:53 INFO - 'sessionrestore_no_auto_restore']}, 19:48:53 INFO - 'svgr': {'tests': ['tsvgx', 19:48:53 INFO - 'tsvgr_opacity', 19:48:53 INFO - 'tart', 19:48:53 INFO - 'tscrollx', 19:48:53 INFO - 'cart']}, 19:48:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['tsvgx', 19:48:53 INFO - 'tsvgr_opacity', 19:48:53 INFO - 'tart', 19:48:53 INFO - 'tscrollx', 19:48:53 INFO - 'cart']}, 19:48:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'tests': ['tp5o']}, 19:48:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'talos_options': ['--e10s'], 19:48:53 INFO - 'tests': ['tp5o']}, 19:48:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'talos_options': ['--xperf_path', 19:48:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:48:53 INFO - 'tests': ['tp5n']}, 19:48:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:53 INFO - 'talos_options': ['--e10s', 19:48:53 INFO - '--xperf_path', 19:48:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:48:53 INFO - 'tests': ['tp5n']}}, 19:48:53 INFO - 'talos.zip': {'path': '', 19:48:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:48:53 INFO - Running post-action listener: _resource_record_post_action 19:48:53 INFO - ##### 19:48:53 INFO - ##### Running create-virtualenv step. 19:48:53 INFO - ##### 19:48:53 INFO - Running pre-action listener: _resource_record_pre_action 19:48:53 INFO - Running main action method: create_virtualenv 19:48:53 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:48:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 19:48:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:48:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:48:53 INFO - Using real prefix '/usr' 19:48:53 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:48:54 INFO - Installing distribute.............................................................................................................................................................................................done. 19:48:56 INFO - Installing pip.................done. 19:48:56 INFO - Return code: 0 19:48:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:48: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')]} 19:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48: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')]} 19:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:48:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 19:48:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:48:56 INFO - Using env: {'DISPLAY': ':0', 19:48:56 INFO - 'HOME': '/home/cltbld', 19:48:56 INFO - 'LANG': 'en_US.UTF-8', 19:48:56 INFO - 'LANGUAGE': 'en_US:en', 19:48:56 INFO - 'LOGNAME': 'cltbld', 19:48:56 INFO - 'MAIL': '/var/mail/cltbld', 19:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:56 INFO - 'MOZ_NO_REMOTE': '1', 19:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:56 INFO - 'NO_EM_RESTART': '1', 19:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:48:56 INFO - 'PWD': '/builds/slave/test-pgo', 19:48:56 INFO - 'SHELL': '/bin/bash', 19:48:56 INFO - 'SHLVL': '1', 19:48:56 INFO - 'TERM': 'linux', 19:48:56 INFO - 'TMOUT': '86400', 19:48:56 INFO - 'USER': 'cltbld', 19:48:56 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:56 INFO - '_': '/tools/buildbot/bin/python'} 19:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:48:56 INFO - Cleaning up... 19:48:56 INFO - Return code: 0 19:48:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:48: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')]} 19:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48: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')]} 19:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:48:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 19:48:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:48:56 INFO - Using env: {'DISPLAY': ':0', 19:48:56 INFO - 'HOME': '/home/cltbld', 19:48:56 INFO - 'LANG': 'en_US.UTF-8', 19:48:56 INFO - 'LANGUAGE': 'en_US:en', 19:48:56 INFO - 'LOGNAME': 'cltbld', 19:48:56 INFO - 'MAIL': '/var/mail/cltbld', 19:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:56 INFO - 'MOZ_NO_REMOTE': '1', 19:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:56 INFO - 'NO_EM_RESTART': '1', 19:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:48:56 INFO - 'PWD': '/builds/slave/test-pgo', 19:48:56 INFO - 'SHELL': '/bin/bash', 19:48:56 INFO - 'SHLVL': '1', 19:48:56 INFO - 'TERM': 'linux', 19:48:56 INFO - 'TMOUT': '86400', 19:48:56 INFO - 'USER': 'cltbld', 19:48:56 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:56 INFO - '_': '/tools/buildbot/bin/python'} 19:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:56 INFO - Downloading/unpacking psutil>=0.7.1 19:48: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 19:48: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 19:48: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 19:48: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 19:48: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 19:48: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 19:48:58 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 19:48:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 19:48:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:48:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:48:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:48:58 INFO - Installing collected packages: psutil 19:48:58 INFO - Running setup.py install for psutil 19:48:58 INFO - building 'psutil._psutil_linux' extension 19:48:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:48:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:48:58 INFO - building 'psutil._psutil_posix' extension 19:48:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:48:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:48:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:48:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:48:58 INFO - Successfully installed psutil 19:48:58 INFO - Cleaning up... 19:48:58 INFO - Return code: 0 19:48:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:48: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')]} 19:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48: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')]} 19:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48: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', '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 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:48: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 19:48: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 mozsystemmonitor==0.0.0 19:48:58 INFO - Using env: {'DISPLAY': ':0', 19:48:58 INFO - 'HOME': '/home/cltbld', 19:48:58 INFO - 'LANG': 'en_US.UTF-8', 19:48:58 INFO - 'LANGUAGE': 'en_US:en', 19:48:58 INFO - 'LOGNAME': 'cltbld', 19:48:58 INFO - 'MAIL': '/var/mail/cltbld', 19:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:58 INFO - 'MOZ_NO_REMOTE': '1', 19:48:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:58 INFO - 'NO_EM_RESTART': '1', 19:48:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:48:58 INFO - 'PWD': '/builds/slave/test-pgo', 19:48:58 INFO - 'SHELL': '/bin/bash', 19:48:58 INFO - 'SHLVL': '1', 19:48:58 INFO - 'TERM': 'linux', 19:48:58 INFO - 'TMOUT': '86400', 19:48:58 INFO - 'USER': 'cltbld', 19:48:58 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:58 INFO - '_': '/tools/buildbot/bin/python'} 19:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:48:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:48:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:49:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 19:49:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:49:00 INFO - Installing collected packages: mozsystemmonitor 19:49:00 INFO - Running setup.py install for mozsystemmonitor 19:49:00 INFO - Successfully installed mozsystemmonitor 19:49:00 INFO - Cleaning up... 19:49:00 INFO - Return code: 0 19:49:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:00 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 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:49:00 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 19:49:00 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 19:49:00 INFO - Using env: {'DISPLAY': ':0', 19:49:00 INFO - 'HOME': '/home/cltbld', 19:49:00 INFO - 'LANG': 'en_US.UTF-8', 19:49:00 INFO - 'LANGUAGE': 'en_US:en', 19:49:00 INFO - 'LOGNAME': 'cltbld', 19:49:00 INFO - 'MAIL': '/var/mail/cltbld', 19:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:00 INFO - 'MOZ_NO_REMOTE': '1', 19:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:00 INFO - 'NO_EM_RESTART': '1', 19:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:49:00 INFO - 'PWD': '/builds/slave/test-pgo', 19:49:00 INFO - 'SHELL': '/bin/bash', 19:49:00 INFO - 'SHLVL': '1', 19:49:00 INFO - 'TERM': 'linux', 19:49:00 INFO - 'TMOUT': '86400', 19:49:00 INFO - 'USER': 'cltbld', 19:49:00 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:00 INFO - '_': '/tools/buildbot/bin/python'} 19:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:01 INFO - Downloading/unpacking blobuploader==1.2.4 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:02 INFO - Downloading blobuploader-1.2.4.tar.gz 19:49:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 19:49:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:49:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:49:02 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 19:49:02 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 19:49:02 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 19:49:02 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 19:49:02 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 19:49:02 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 19:49:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 19:49:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:49:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:03 INFO - Downloading docopt-0.6.1.tar.gz 19:49:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 19:49:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:49:03 INFO - Installing collected packages: blobuploader, requests, docopt 19:49:03 INFO - Running setup.py install for blobuploader 19:49:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:49:03 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:49:03 INFO - Running setup.py install for requests 19:49:03 INFO - Running setup.py install for docopt 19:49:04 INFO - Successfully installed blobuploader requests docopt 19:49:04 INFO - Cleaning up... 19:49:04 INFO - Return code: 0 19:49:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:49:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 19:49:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:49:04 INFO - Using env: {'DISPLAY': ':0', 19:49:04 INFO - 'HOME': '/home/cltbld', 19:49:04 INFO - 'LANG': 'en_US.UTF-8', 19:49:04 INFO - 'LANGUAGE': 'en_US:en', 19:49:04 INFO - 'LOGNAME': 'cltbld', 19:49:04 INFO - 'MAIL': '/var/mail/cltbld', 19:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:04 INFO - 'MOZ_NO_REMOTE': '1', 19:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:04 INFO - 'NO_EM_RESTART': '1', 19:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:49:04 INFO - 'PWD': '/builds/slave/test-pgo', 19:49:04 INFO - 'SHELL': '/bin/bash', 19:49:04 INFO - 'SHLVL': '1', 19:49:04 INFO - 'TERM': 'linux', 19:49:04 INFO - 'TMOUT': '86400', 19:49:04 INFO - 'USER': 'cltbld', 19:49:04 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:04 INFO - '_': '/tools/buildbot/bin/python'} 19:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:49:04 INFO - Running setup.py (path:/tmp/pip-3WU01i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:49:04 INFO - Running setup.py (path:/tmp/pip-encH5x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:49:04 INFO - Running setup.py (path:/tmp/pip-Ob5Z83-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:49:04 INFO - Running setup.py (path:/tmp/pip-6PuECV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:49:04 INFO - Running setup.py (path:/tmp/pip-oqyZ5D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:49:04 INFO - Running setup.py (path:/tmp/pip-55wKyw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:49:04 INFO - Running setup.py (path:/tmp/pip-hcddiu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:49:04 INFO - Running setup.py (path:/tmp/pip-AS8n5d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:49:04 INFO - Running setup.py (path:/tmp/pip-VjbvxY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:49:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:49:04 INFO - Running setup.py (path:/tmp/pip-zH3Ub5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:49:05 INFO - Running setup.py (path:/tmp/pip-Gew5dN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:49:05 INFO - Running setup.py (path:/tmp/pip-X7ANhg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:49:05 INFO - Running setup.py (path:/tmp/pip-EUyIfC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:49:05 INFO - Running setup.py (path:/tmp/pip-pofO1g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:49:05 INFO - Running setup.py (path:/tmp/pip-ToCBYZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:49:05 INFO - Running setup.py (path:/tmp/pip-huk6xz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:49:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:49:05 INFO - Running setup.py (path:/tmp/pip-SUJ8HE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:49:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:49:05 INFO - Running setup.py install for manifestparser 19:49:05 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:49:05 INFO - Running setup.py install for mozcrash 19:49:05 INFO - Running setup.py install for mozdebug 19:49:05 INFO - Running setup.py install for mozdevice 19:49:05 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:49:05 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:49:05 INFO - Running setup.py install for mozfile 19:49:06 INFO - Running setup.py install for mozhttpd 19:49:06 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Running setup.py install for mozinfo 19:49:06 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Running setup.py install for mozInstall 19:49:06 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Running setup.py install for mozleak 19:49:06 INFO - Running setup.py install for mozlog 19:49:06 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Running setup.py install for moznetwork 19:49:06 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Running setup.py install for mozprocess 19:49:06 INFO - Running setup.py install for mozprofile 19:49:06 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:49:06 INFO - Running setup.py install for mozrunner 19:49:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:49:07 INFO - Running setup.py install for mozscreenshot 19:49:07 INFO - Running setup.py install for moztest 19:49:07 INFO - Running setup.py install for mozversion 19:49:07 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:49:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:49:07 INFO - Cleaning up... 19:49:07 INFO - Return code: 0 19:49:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:49:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 19:49:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:49:07 INFO - Using env: {'DISPLAY': ':0', 19:49:07 INFO - 'HOME': '/home/cltbld', 19:49:07 INFO - 'LANG': 'en_US.UTF-8', 19:49:07 INFO - 'LANGUAGE': 'en_US:en', 19:49:07 INFO - 'LOGNAME': 'cltbld', 19:49:07 INFO - 'MAIL': '/var/mail/cltbld', 19:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:07 INFO - 'MOZ_NO_REMOTE': '1', 19:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:07 INFO - 'NO_EM_RESTART': '1', 19:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:49:07 INFO - 'PWD': '/builds/slave/test-pgo', 19:49:07 INFO - 'SHELL': '/bin/bash', 19:49:07 INFO - 'SHLVL': '1', 19:49:07 INFO - 'TERM': 'linux', 19:49:07 INFO - 'TMOUT': '86400', 19:49:07 INFO - 'USER': 'cltbld', 19:49:07 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:07 INFO - '_': '/tools/buildbot/bin/python'} 19:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:49:07 INFO - Running setup.py (path:/tmp/pip-s84c14-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:49:07 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)) 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:49:07 INFO - Running setup.py (path:/tmp/pip-zOjXIp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:49:07 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)) 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:49:07 INFO - Running setup.py (path:/tmp/pip-LU4Qkr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:49:07 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)) 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:49:07 INFO - Running setup.py (path:/tmp/pip-86pPi0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:49:07 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)) 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:49:07 INFO - Running setup.py (path:/tmp/pip-YYCpOJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:49:07 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)) 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:49:07 INFO - Running setup.py (path:/tmp/pip-vSj0SI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:49:07 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)) 19:49:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:49:07 INFO - Running setup.py (path:/tmp/pip-TTWGRn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:49:08 INFO - Running setup.py (path:/tmp/pip-j4W3D_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:49:08 INFO - Running setup.py (path:/tmp/pip-AqcRYH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:49:08 INFO - Running setup.py (path:/tmp/pip-Lh0lRk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:49:08 INFO - Running setup.py (path:/tmp/pip-SWRMbR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:49:08 INFO - Running setup.py (path:/tmp/pip-nyTRzL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:49:08 INFO - Running setup.py (path:/tmp/pip-XjEj0d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:49:08 INFO - Running setup.py (path:/tmp/pip-q5x82i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:49:08 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)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:49:08 INFO - Running setup.py (path:/tmp/pip-Q2f2vl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:49:08 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)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:49:08 INFO - Running setup.py (path:/tmp/pip-C10UX5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:49:08 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)) 19:49:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:49:08 INFO - Running setup.py (path:/tmp/pip-eP14Ll-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:49:08 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)) 19:49:08 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)) 19:49: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.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 19:49:08 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)) 19:49: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 mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 19:49:08 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)) 19:49:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:49:08 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 19:49:08 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 19:49:08 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 19:49:08 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 19:49:08 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 19:49:08 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 19:49:10 INFO - Downloading blessings-1.5.1.tar.gz 19:49:10 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 19:49:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:49:10 INFO - Installing collected packages: blessings 19:49:10 INFO - Running setup.py install for blessings 19:49:10 INFO - Successfully installed blessings 19:49:10 INFO - Cleaning up... 19:49:10 INFO - Return code: 0 19:49:10 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:49:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:49:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:49:10 INFO - Reading from file tmpfile_stdout 19:49:10 INFO - Current package versions: 19:49:10 INFO - argparse == 1.2.1 19:49:10 INFO - blessings == 1.5.1 19:49:10 INFO - blobuploader == 1.2.4 19:49:10 INFO - docopt == 0.6.1 19:49:10 INFO - manifestparser == 1.1 19:49:10 INFO - mozInstall == 1.12 19:49:10 INFO - mozcrash == 0.16 19:49:10 INFO - mozdebug == 0.1 19:49:10 INFO - mozdevice == 0.47 19:49:10 INFO - mozfile == 1.2 19:49:10 INFO - mozhttpd == 0.7 19:49:10 INFO - mozinfo == 0.9 19:49:10 INFO - mozleak == 0.1 19:49:10 INFO - mozlog == 3.1 19:49:10 INFO - moznetwork == 0.27 19:49:10 INFO - mozprocess == 0.22 19:49:10 INFO - mozprofile == 0.27 19:49:10 INFO - mozrunner == 6.11 19:49:10 INFO - mozscreenshot == 0.1 19:49:10 INFO - mozsystemmonitor == 0.0 19:49:10 INFO - moztest == 0.7 19:49:10 INFO - mozversion == 1.4 19:49:10 INFO - psutil == 3.1.1 19:49:10 INFO - requests == 1.2.3 19:49:10 INFO - wsgiref == 0.1.2 19:49:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:49:10 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')]} 19:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:10 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')]} 19:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:10 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 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:49:10 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 19:49:10 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 19:49:10 INFO - Using env: {'DISPLAY': ':0', 19:49:10 INFO - 'HOME': '/home/cltbld', 19:49:10 INFO - 'LANG': 'en_US.UTF-8', 19:49:10 INFO - 'LANGUAGE': 'en_US:en', 19:49:10 INFO - 'LOGNAME': 'cltbld', 19:49:10 INFO - 'MAIL': '/var/mail/cltbld', 19:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:10 INFO - 'MOZ_NO_REMOTE': '1', 19:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:10 INFO - 'NO_EM_RESTART': '1', 19:49:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:49:10 INFO - 'PWD': '/builds/slave/test-pgo', 19:49:10 INFO - 'SHELL': '/bin/bash', 19:49:10 INFO - 'SHLVL': '1', 19:49:10 INFO - 'TERM': 'linux', 19:49:10 INFO - 'TMOUT': '86400', 19:49:10 INFO - 'USER': 'cltbld', 19:49:10 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:10 INFO - '_': '/tools/buildbot/bin/python'} 19:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 19:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:49:11 INFO - Cleaning up... 19:49:11 INFO - Return code: 0 19:49:11 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19db030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c087d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', '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 19:49:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 19:49:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:49:11 INFO - Using env: {'DISPLAY': ':0', 19:49:11 INFO - 'HOME': '/home/cltbld', 19:49:11 INFO - 'LANG': 'en_US.UTF-8', 19:49:11 INFO - 'LANGUAGE': 'en_US:en', 19:49:11 INFO - 'LOGNAME': 'cltbld', 19:49:11 INFO - 'MAIL': '/var/mail/cltbld', 19:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:11 INFO - 'MOZ_NO_REMOTE': '1', 19:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:11 INFO - 'NO_EM_RESTART': '1', 19:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:49:11 INFO - 'PWD': '/builds/slave/test-pgo', 19:49:11 INFO - 'SHELL': '/bin/bash', 19:49:11 INFO - 'SHLVL': '1', 19:49:11 INFO - 'TERM': 'linux', 19:49:11 INFO - 'TMOUT': '86400', 19:49:11 INFO - 'USER': 'cltbld', 19:49:11 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:11 INFO - '_': '/tools/buildbot/bin/python'} 19:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:11 INFO - Downloading/unpacking jsonschema 19:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:49:12 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 19:49:12 INFO - Downloading/unpacking functools32 (from jsonschema) 19:49:12 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 19:49:12 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 19:49:12 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 19:49:12 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 19:49:12 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 19:49:12 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 19:49:13 INFO - Downloading functools32-3.2.3-2.tar.gz 19:49:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 19:49:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:49:13 INFO - warning: no files found matching '*.txt' 19:49:13 INFO - no previously-included directories found matching 'build' 19:49:13 INFO - no previously-included directories found matching 'dist' 19:49:13 INFO - no previously-included directories found matching '.git*' 19:49:13 INFO - Installing collected packages: jsonschema, functools32 19:49:13 INFO - Running setup.py install for functools32 19:49:13 INFO - warning: no files found matching '*.txt' 19:49:13 INFO - no previously-included directories found matching 'build' 19:49:13 INFO - no previously-included directories found matching 'dist' 19:49:13 INFO - no previously-included directories found matching '.git*' 19:49:13 INFO - Successfully installed jsonschema functools32 19:49:13 INFO - Cleaning up... 19:49:13 INFO - Return code: 0 19:49:13 INFO - Running post-action listener: _resource_record_post_action 19:49:13 INFO - Running post-action listener: _start_resource_monitoring 19:49:13 INFO - Starting resource monitoring. 19:49:13 INFO - ##### 19:49:13 INFO - ##### Running install step. 19:49:13 INFO - ##### 19:49:13 INFO - Running pre-action listener: _resource_record_pre_action 19:49:13 INFO - Running main action method: install 19:49:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:49:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:49:13 INFO - Reading from file tmpfile_stdout 19:49:13 INFO - Detecting whether we're running mozinstall >=1.0... 19:49:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:49:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:49:13 INFO - Reading from file tmpfile_stdout 19:49:13 INFO - Output received: 19:49:13 INFO - Usage: mozinstall [options] installer 19:49:13 INFO - Options: 19:49:13 INFO - -h, --help show this help message and exit 19:49:13 INFO - -d DEST, --destination=DEST 19:49:13 INFO - Directory to install application into. [default: 19:49:13 INFO - "/builds/slave/test-pgo"] 19:49:13 INFO - --app=APP Application being installed. [default: firefox] 19:49:13 INFO - mkdir: /builds/slave/test-pgo/build/application 19:49:13 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'] 19:49:13 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 19:49:28 INFO - Reading from file tmpfile_stdout 19:49:28 INFO - Output received: 19:49:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:49:28 INFO - Running post-action listener: _resource_record_post_action 19:49:28 INFO - ##### 19:49:28 INFO - ##### Running run-tests step. 19:49:28 INFO - ##### 19:49:28 INFO - Running pre-action listener: _resource_record_pre_action 19:49:28 INFO - Running main action method: run_tests 19:49:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:49:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:49:28 INFO - Python 2.7.3 19:49:28 INFO - Return code: 0 19:49:28 INFO - grabbing minidump binary from tooltool 19:49:28 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')]} 19:49:28 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 0x1c97640>, '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 0x1c98540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c989d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:49:28 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 19:49:28 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 19:49:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:49:28 INFO - Return code: 0 19:49:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:49:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:49:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:49:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:49:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:49:28 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/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 19:49:28 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/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost 19:49:28 INFO - Using env: {'DISPLAY': ':0', 19:49:28 INFO - 'HOME': '/home/cltbld', 19:49:28 INFO - 'LANG': 'en_US.UTF-8', 19:49:28 INFO - 'LANGUAGE': 'en_US:en', 19:49:28 INFO - 'LOGNAME': 'cltbld', 19:49:28 INFO - 'MAIL': '/var/mail/cltbld', 19:49:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:49:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:28 INFO - 'MOZ_NO_REMOTE': '1', 19:49:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:28 INFO - 'NO_EM_RESTART': '1', 19:49:28 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:49:28 INFO - 'PWD': '/builds/slave/test-pgo', 19:49:28 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:49:28 INFO - 'SHELL': '/bin/bash', 19:49:28 INFO - 'SHLVL': '1', 19:49:28 INFO - 'TERM': 'linux', 19:49:28 INFO - 'TMOUT': '86400', 19:49:28 INFO - 'USER': 'cltbld', 19:49:28 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335', 19:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:28 INFO - '_': '/tools/buildbot/bin/python'} 19:49:28 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/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] with output_timeout 3600 19:49:28 INFO - mozversion INFO | application_buildid: 20151124173007 19:49:28 INFO - mozversion INFO | application_changeset: dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23 19:49:28 INFO - mozversion INFO | application_display_name: Nightly 19:49:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:49:28 INFO - mozversion INFO | application_name: Firefox 19:49:28 INFO - mozversion INFO | application_remotingname: firefox 19:49:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 19:49:28 INFO - mozversion INFO | application_vendor: Mozilla 19:49:28 INFO - mozversion INFO | application_version: 45.0a1 19:49:28 INFO - mozversion INFO | platform_buildid: 20151124173007 19:49:28 INFO - mozversion INFO | platform_changeset: dbf3d11d0f9ed1941ba5323b51fbdb2666b26f23 19:49:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 19:49:28 INFO - mozversion INFO | platform_version: 45.0a1 19:49:28 INFO - 2015-11-24 19:49:28,645 DEBUG : using testdate: 1448423368 19:49:28 INFO - 2015-11-24 19:49:28,645 DEBUG : actual date: 1448423368 19:49:28 INFO - 2015-11-24 19:49:28,654 INFO : starting webserver on 'localhost:50702' 19:49:28 INFO - 2015-11-24 19:49:28,655 INFO : Starting test suite talos-linux64-ix-009 19:49:28 INFO - 2015-11-24 19:49:28,655 INFO : Starting test dromaeo_css 19:49:28 INFO - 2015-11-24 19:49:28,655 DEBUG : operating with platform_type : linux_ 19:49:28 INFO - 2015-11-24 19:49:28,656 INFO : Initialising browser for dromaeo_css test... 19:49:28 INFO - 2015-11-24 19:49:28,670 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEBBlhi/profile http://localhost:50702/getInfo.html 19:49:32 INFO - 2015-11-24 19:49:32,465 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:49:32 INFO - 2015-11-24 19:49:32,465 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:49:32 INFO - 2015-11-24 19:49:32,465 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:49:32 INFO - 2015-11-24 19:49:32,465 DEBUG : BROWSER_OUTPUT: __metrics 19:49:32 INFO - 2015-11-24 19:49:32,546 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] 19:49:33 INFO - 2015-11-24 19:49:33,839 INFO : Browser initialized. 19:49:33 INFO - 2015-11-24 19:49:33,840 INFO : Running cycle 1/1 for dromaeo_css test... 19:49:33 INFO - 2015-11-24 19:49:33,840 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEBBlhi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:49:45 INFO - 2015-11-24 19:49:45,620 DEBUG : BROWSER_OUTPUT: RSS: Main: 139251712 19:49:45 INFO - 2015-11-24 19:49:45,620 DEBUG : BROWSER_OUTPUT: 19:50:36 INFO - 2015-11-24 19:50:36,505 DEBUG : BROWSER_OUTPUT: 1448423436504 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:52:46 INFO - 2015-11-24 19:52:46,343 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50702/tests/dromaeo/cssquery-ext.html) 19:52:47 INFO - 2015-11-24 19:52:47,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 246841344 19:52:47 INFO - 2015-11-24 19:52:47,496 DEBUG : BROWSER_OUTPUT: 19:55:49 INFO - 2015-11-24 19:55:49,820 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/dromaeo/cssquery-ext.html (next: http://localhost:50702/tests/dromaeo/cssquery-jquery.html) 19:55:50 INFO - 2015-11-24 19:55:50,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 224694272 19:55:50 INFO - 2015-11-24 19:55:50,386 DEBUG : BROWSER_OUTPUT: 19:58:52 INFO - 2015-11-24 19:58:52,310 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50702/tests/dromaeo/cssquery-mootools.html) 19:58:52 INFO - 2015-11-24 19:58:52,876 DEBUG : BROWSER_OUTPUT: RSS: Main: 219426816 19:58:52 INFO - 2015-11-24 19:58:52,876 DEBUG : BROWSER_OUTPUT: 20:01:54 INFO - 2015-11-24 20:01:54,811 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50702/tests/dromaeo/cssquery-prototype.html) 20:01:55 INFO - 2015-11-24 20:01:55,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 197345280 20:01:55 INFO - 2015-11-24 20:01:55,372 DEBUG : BROWSER_OUTPUT: 20:04:57 INFO - 2015-11-24 20:04:57,849 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50702/tests/dromaeo/cssquery-yui.html) 20:04:58 INFO - 2015-11-24 20:04:58,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 203141120 20:04:58 INFO - 2015-11-24 20:04:58,430 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/dromaeo/cssquery-yui.html (next: http://localhost:50702/tests/dromaeo/cssquery-dojo.html) 20:08:01 INFO - 2015-11-24 20:08:01,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 253296640 20:08:01 INFO - 2015-11-24 20:08:01,324 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,326 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:08:01 INFO - 2015-11-24 20:08:01,326 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:08:01 INFO - 2015-11-24 20:08:01,326 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:08:01 INFO - 2015-11-24 20:08:01,326 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:08:01 INFO - 2015-11-24 20:08:01,328 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3174.7936384135032;3202.5836641236638;3244.318693074454;3256.3324518473714;3262.151840521464;5191.5327620514145;5342.931413717262;5379.999999999995;5391.541718953894;5421.349438067433;5343.839684809462;5349.3045904032515;5373.946260537394;5412.404635490103;5464.3442786865635;4945.505449455061;4996.550310472066;5020.246962955549;5057.089723849724;5063.443021267658;5021.899562008758;5044.899102017957;5046.066477701618;5112.539871411572;5121;5058.848234552933;5063.088644044071;5108.361454818148;5131.0764062468925;5153.6392452528335;4786.2581299898575;4895.090914543331;4933.1120398328285;5028.044671512401;5033.043721692867;4965.155923492979;4965.851024469272;4969.875753106165;4989.101961646902;5026.974865125651;5141.383034035902;5234.581233501311;5280.128778751501;5280.6039547034125;5291.603129765283;5782.681952492611;5825.650460972312;5832.387599302097;5858.56060795442;5889.528837692974;5908.970455147697;5929.080992446177;5929.436703513159;5929.466348028698;5933.19901813254;5320.707361095099;5422.240886275925;5531.363893152299;5537.1140617501005;5545.584081193927;5901.380355062656;5905.291365036223;5967.224260846062;5982.162497250389;5983.940160598426;5790.855228619234;5814.709264536757;5815.709214539256;5818.389069147764;5840.649561026352;5713.657180569179;5776.046952252916;5863.970680146657;5870.295564532198;5926.674032928144;5808.477237048686;5889.734961926723;5892.322382925932;5897.144913987534;5939.10913362999;5840.532757379399;5882.441168089024;5886.352501224862;5886.852828679317;5889.911651325234;4357.411749413852;4445.199864024442;4486.977565112219;4541.931871021938;4548.11311794197;4066.1664358806374;4076.449679293317;4097.037196258877;4116.238495878272;4134.565870583606;5572.08060669993;5582.441755824385;5587.217789509472;5587.636803607753;5590.8043218487155;5702;5828;5836.182934389189;5939.07944268639;5949.167116603679;5521.282233309725;5617.073182924853;5621.15682647598;5626.662400255997;5668.829935101953;5130.204818253177;5150.922736158961;5154.716490593053;5157.690538567698;5172.250023746536;5857.297124345106;5977.282726072899;6065.363136870565;6122.479589234876;6137.96931015351;5744.167095771007;5836.970815145897;5923.940760592339;5972.432618901197;5985.1022346646605;6052.273727152594;6060.99993501085;6065.363136870742;6066.363031881765;6071.514278857592;5707;5762.625429347079;5765.336986246468;5769.134629805562;5781.4507621775865;5702.600817942704;5771.7402716877;5808.186853840381;5837.562182836219;5940.257467816523;5753.367129615655;5757.596968212352;5760.481556659921;5771.59598828078;5775.306963164448;5747.36778954323;5752.626079304831;5755.165501002415;5759.71201439918;5761.827145185651;5428.972855135699;5498.725063746877;5556.833295001156;5558.166275058612;5671.376148623731;5764.308283006066;5787.681677507778;5805.506531944663;5817.069268916953;5869.941300586939;5624.240727501735;5672.5745569081655;5675.858103547279;5679.204911312502;5683.318001839806 20:08:01 INFO - 2015-11-24 20:08:01,329 DEBUG : BROWSER_OUTPUT: |1;ext.html;564.4347033762654;580.4543728894841;593.6675461741426;607.4563265877039;623.2365352443255;32206.35587288253;37834.621653783455;38168;38242.235155296876;38252.80873595628;43009.7849510752;43795.78102109493;43889.561104388944;43928.56071439285;43963.56036439635;7656.464047516676;7758.7672369828715;8606.440581362207;8839.337049721264;10064.698059058202;10185.33795303303;10287.022732840405;10300.639477618284;10301.896981030206;10304;10297.382157070562;10338.069573738361;10418.27072104953;10426.478676066205;10430.68707938763;7644.426667999895;7765.72819951299;10264.794704105876;10570.577176912915;10573.365598064142;10185.541650625739;10307.84538231927;10347.793044139074;10348.275620706554;10351;40423.7978810104;40775.7961210195;40804.59195408067;40835.795821021005;40894.79552602248;2978.7616990640695;2991.5064014437594;3003.639563252402;3028.6971302869756;3029.9242518937203;30043;30702.692973070207;30829.075127746204;30887.845560772053;31260.062198134092;3548.1307079765625;3560.12776869666;3596.5504311961004;3679.2825399047197;3683.4658974448553;276395;277300;277916;278118;278382;55199.72400138054;55900;61511.6924415375;61838.690806546576;61919.38080619226;70427;72495.27504724886;72886.271137289;72892;73003.63498182475;13927.651808704863;13941.512047078304;13946.023778335622;14033.087849289763;14561.563153105424;10265.384076955406;10304.381737095799;10305.175585953255;10309.948450257702;10310;20394.69407958882;20864.895675521526;20882.895585521976;20908.163673453197;20956.895215523826;7971.362291016705;8253.546054967035;8263.752087437266;8347.833043339098;8360.623771930277;943.3726571829657;948.8292107420731;995.8307087794948;1036.9792604147874;1133.9943300283446;28799.85600071986;29179.27051823679;29273.82414816933;29625.25936851596;29776.255593610334;990.841465365538;1016.9593216271414;1065.163846380603;1087.722630729159;1099.4173088263233;14430.278486075655;14656.120632762068;14967.326470308863;15078.321475533627;15233.23833808305;386991;389962;390326;390520;394648;55343.72328138333;56024.1566180735;56028.43971560314;56035.71982140145;56120;1328.754180476615;1334.9265790381428;1352.1684164239118;1356.1456282542133;1358.626377746108;28346;28777.42445151127;28807;29261.70738292675;29265.268368290963;45935;46476.767616161706;46586.301205481264;46602.766986164854;46605.76697116493;304515;307116;307536;307564;308238;28617.284567885137;28964.42071158523;32133.196670083435;32643.836780816895;32917.341653166324;39149.412758808074;39596;40166.397504036875;40266.59733402746;40611.390829136995;32770;33706;34175.14562135967;34341.484877726354;36440.63559364479;28650;33266;36490.270194595425;37035.444468332455;37348.81325593355;33030.17424564309;33227.50158747572;33404;33882;34307.485387718705;30895.691043089282;32624.510632340058;32719.182020448727;32846;32889.86215895959;24349.87825060863;24461.266162015167;24898.875505622356;25118.372040699127;25318.493630126926 20:08:01 INFO - 2015-11-24 20:08:01,333 DEBUG : BROWSER_OUTPUT: |2;jquery.html;348.9860405583777;350.3815765174468;350.80530305680344;351.30967648570555;356.1807841963483;14350.067245629027;14704.558863234093;16247.431339903105;16822.327106915724;17824.287028518876;15579.53261402157;16394.09832459214;16731.414400495985;16748.246328915197;16772.245248963794;5142.897142057157;5250.684958902459;5257.579393648518;5262.0265250928505;5266.710330931797;4001.1597564511344;4107.014316564033;5272.077386457374;5299.364076310848;5801.274840644919;5819.214406055171;5848.385919478457;5880.9411905880825;5880.97059514704;5902.940970590282;5691.886162276752;5798.072308430631;5837.50381217597;5842.795502157425;5851.7074146292725;5071.898562028776;5093.719845408501;5114.872128196787;5121.129408000649;5125.538701516844;18820.153093110843;20252.392428227176;20338.898305508526;20683.172673093057;21163.306935445282;2163.642998905179;2188.616992026389;2212.9778702212934;2630.842149471038;2633.8814753336146;13010.869891301156;13091.476340946447;13128.540501082416;13359.465621375133;13518.121322114031;1720.8623310135158;1734.722444408901;1753.6054387762779;1787.2225581871796;1857.8978156201404;54348;54678.72660636711;55185.448145518436;55327.72336138334;59642.70178649123;9484.525773711286;9733.805323893483;9795.461249631338;9800.754981125529;9879.654212102541;12960.157589756636;13005.089643724848;13008.154469959421;13010.089293749535;13023.348832558335;6913.75801846943;6964.164300283897;6981.79054628362;7080.822979425556;7115.075040244735;6875.690593923286;6898.689558969859;7025.754098606524;7031.402330801837;7063.788086357315;4326;4347.369631403492;4359.17175736609;4362.672799539983;4387.824487020547;1575.58247064528;1604.1658337664583;1761.3483011285907;1797.532641513216;1813.3200049981258;402.3140545370137;416.8916081818749;422.780154319752;444.3512471791221;854.89291367679;10040.447775372424;10047.799044019224;10581.735456613493;10700.678979630467;10794.568217271379;439.27081045464377;851.1871163039283;874.7506960516222;951.4529145741227;980.8087422952456;7344.632768361667;9369.297302702325;9376.109269619375;9399.765005874908;9548.140667339972;385417;396181;547353;547882;555651;77236;77624;80248;80491;82237.88810559448;1096.4024606589428;1119.9552017919193;1125.7129431994952;1126.4987080748988;1129.0459561670539;15328.770068448759;15358.84641153619;15390.384384624489;15652.765208522109;15767.290471928562;33883.32233355368;35203;35315.47026794651;35779.28441431105;36437.27125457529;45160.54839451695;45425;46424.30363544482;46812.53187468082;46915.765421174045;9374.250059995047;9527.571259293487;9533.713988580354;9560.426374417557;9586.472743999147;10725.195610329101;14946.476873309382;17189.91405043017;17214.4896858836;17644;14144.939129565118;14346.354414051602;14425.42298308056;14643.12141271527;14717.852821471648;13919.721605568035;13974.161550307013;14122.293885305487;14219.431222750974;14533;11626.70933226676;11773.293602383885;11909.82135267989;13955.511557095453;14129;9185.586648600696;9260.953695231481;9275.86086208694;9276.6289348428;9413.105754953269;9815.950920245354;10021.949890250502;10516.631917882838;13757.243351615758;14001.92999035039 20:08:01 INFO - 2015-11-24 20:08:01,333 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1335.559265442404;1693.4919524142756;1738.1743671755917;1765.9558511037214;1782.4385318624632;6319.39965703258;7655.4641175117695;7823.804404889881;7834.059912810457;7845.490043147199;7572;7804.687812487494;7813.179616140309;7818.648160832762;7818.921810781891;1924.7497825282694;1953.2675246782458;2047.2629853252818;2233.8994745236464;2265.7054582904284;2246.955060898781;2253.6281513550243;2256.255435706213;2256.808171305441;2257.954840903181;2157.4174972757346;2236.105557776886;2237.071615279657;2240.5182885679574;2241.68616393705;1908.5991941692191;1956.8825870447747;2186.3987403464016;2233.8212942964687;2236.7986881180773;2090.874547527153;2101.3065688322818;2108.104055776289;2113.7146485224453;2135.487483004068;7717.151113377524;7846.3330616897065;7847.489913155684;7854.803629909298;7858.0570331560575;4554.795034223468;4680.976595117003;4701.435827700664;4703.2004559225015;4708.552687494683;2991.925201869971;3012.7439167670773;3034.286942568495;3055.709707577799;3112.097491727396;938.3994243684048;943.1511639524414;944.4522177137244;954.990450095504;979.8481235408452;10693.78612427747;10721.249512534127;10722.517486713117;10726.410047447387;10741.677749667522;4110.23960567296;4191.811368488365;4216.2832318505925;4224.9155016899485;4225.873223803291;9163.266938644892;9205.585748641328;9228.215601673799;9229.446233226028;9234.630614775468;4676.462206846189;4687.507811679725;4689.531046895351;4707.929381059287;4714.952850471452;4241.809118589671;4243.321068629005;4271.807768650418;4278.058827058042;4280.122574872206;3553.502509648651;3569.928601427957;3582.964170358263;3604.18905746205;3633.636636336398;1192.3084610925641;1213.8604060532975;1218.4943248551929;1220.0178856020812;1230.4340003598265;958.3914214473859;1220.859601145862;1235.5243231355812;1247.1270110922392;1277.8274932883942;6299.96850015747;7176.7129314826825;7365.226651201654;7374.410047196211;7378.889316660255;617.1822335405606;630.6815058395573;636.1761518833122;643.0386572074684;647.2136354329513;5453.209284653783;5584.916226256528;5621.943780562224;5623.0721930880945;5640.379558248589;9239.122283383067;9297.07029297065;9390;9408.43549387039;9410.623575056921;9127.357075726433;9243.39917905334;9292.53537323131;9323.20752736025;9402.05979402068;7252.419806415474;7292.635368231568;7299.306565876233;7308.232635573295;7319.085114360705;6959.4432445405255;7042.683079261343;7054.788356349318;7066.116735408074;7098.645067746489;8072.636731347221;8282.585870706562;8304.58477076156;8306.460080094774;8413;8446;8516.276116530164;8537.701180458655;8656.697015604424;8660.437071590326;6869.244383117953;6909.239983601699;6918.5502942308585;6926.653667316715;6927.89608155868;7315.963420182865;7556.622168891643;7570.242975702386;7570.621468926642;7617.5048621839405;7124.21633620291;7191.712331506681;7222.241664625139;7278.417726581755;7312.5246858954;6996.160460744743;6998.825029374307;7069.328413800681;7084.25615310385;7151.177614574182;5917.260342457193;6185.628862268279;7138.892916606359;7155.24869888675;7174.892376614459;6279.748810047729;6365.745370185141;6411.743530258924;6416.679166041773;6435.388638079375;6530.118434011348;6609.702563384563;6610.9669451652435;6636.435902948304;6639.867202656016 20:08:01 INFO - 2015-11-24 20:08:01,334 DEBUG : BROWSER_OUTPUT: |4;prototype.html;327.13962279205685;331.134084369374;332.35191376815214;332.5743048897412;335.1838273803289;5989.281286245651;6011.458968692817;6052;6055.2430946131735;6060.969695151518;5934.139549765292;5937.7624895004255;5946.137810017545;5952.107183922414;5956.940430595692;4620.745858977772;4621.722696638196;4623.375844261023;4623.69945953512;4629.375034370368;4538.273876179812;4557.453105627313;4611.93082103767;4612.308153776927;4628;4555.931661025087;4561.270196768518;4576.405067341241;4576.450825900896;4579.335996280537;4363.3018717005125;4544.818207271689;4553.567411095941;4554.476235232941;4561.384213131218;3891.9221615567812;3925;3943.935137512884;4036.7577945323374;4040.838366465338;5654.491095801356;5677.977963966485;5704.857378565527;5704.914426283609;5708.7145642718115;5013.273075404082;5298.15229563268;5300.072487314705;5303.310569625924;5306.602004849613;6147.985582378902;6198.3801619838105;6310.92714238576;6314.400131987454;6332.1135041094285;4417.469903611555;4420.160169567772;4422.29243321067;4422.7346359218545;4425.557444255549;6535.967320163416;6539;6540.93459065408;6541.47668186544;6542.247641521239;4442.4669039715445;4479.081788233404;4535.841245556389;4542.954570454319;4550.7497087660495;4586.610138138229;4588.449386073626;4589.196890544142;4589.79345929434;4595.058013107305;5290.180022096504;5297.682139071668;5298.417174110922;5298.735063246822;5308;6240.594361366497;6338.714757835908;6357.713902874381;6358.078078678565;6371.139896113966;1616.8463995920604;1638.1399765123406;1640.4668482742918;1644.6546225292823;1646.037068315049;1073.9194560408;1077.7790552936629;1094.9835752463719;1095.8712526098132;1098.5715570927346;6102.084687296941;6105.419985101408;6114.755409783559;6125.264968203844;6131.816045518642;6534.575252608565;6536.542442029012;6539.50953678476;6546.24718157418;6561.14705088345;5986.670733109634;6099.2680878294;6118.082287656887;6123.785667501616;6126.448619624255;3534.9218488360784;3552.0409489437707;3552.182997910443;3555.822208889545;3555.8933232003083;6778.186617605918;6778.2205046420295;6779;6780.932190678129;6781.728730850809;6679.298673639295;6680.565763225375;6694.564853284521;6696.765613203612;6701.932980670228;6597.076409302702;6634.668266586651;6696.799096027127;6746.628935408599;6832.59004459724;6582.539222254396;6596.175478065242;6603.141591593062;6616.338366163345;6617.8014659560295;6512.153420055362;6534.575252608565;6539.509536784855;6540.705668244846;6542.803715888531;6661;6838.04267402574;6840.452763778786;6840.657967101525;6853.520253582202;4374.081442897027;4395.560443955579;4397.472303323621;4399;4437.068215674744;6616.040674102132;6619.0733297339375;6620.635865027277;6628.867422651617;6628.867422651617;6273.843153921006;6385.521085918667;6400.743970241138;6401.423871851555;6419;6342;6345;6372.681365931778;6377.8724425512155;6387.5209359297305;6289.213848268966;6296.716647750771;6299.811005669837;6303.779367722108;6305.4009869062365;6278.497720182466;6450.48396128318;6451.967740161457;6467.514936379696;6473.741050357933;6237.96881015592;6257.937420625735;6258.561900666909;6259.874802504016;6273.905891411541 20:08:01 INFO - 2015-11-24 20:08:01,335 DEBUG : BROWSER_OUTPUT: |5;yui.html;683.5795985468935;739.3789217057671;769.2077160524661;811.5455345006794;815.2051749544195;14041.648958776022;14440.494582689607;14523;14524.63688407789;14552.636184095403;13676.111052781587;13749.931250343734;13801.654958626013;14678.926605366958;15122.773158402606;700.1178515070997;834.7871292820322;849.4733265375445;850.1116333431579;850.7447765670274;700.1912790726719;704.7885634309712;705.2735682247294;820.6389188756958;824.8432797768435;851.5188918261163;852.7058164933119;859.4113032572676;859.6432480520576;860.8493513635088;853.6713365354321;853.7609469348592;853.8164294676675;854.4744981836158;867.2065060469663;529.9841004769863;534.5963797332988;538.1309185665161;641.9743210271583;642.0497663458061;13885.958553108558;13894.861051389458;13937.303134843218;13953.372098255602;14074.08518446308;11144.219904606682;11369.658910232707;11412.885871141349;11435.828462573068;11480.598179063692;9370.812583748288;9574.281928855295;9646.372985755974;9799.363041402286;9906.356086854332;3464.9826750866087;3526.9647303526895;3538.752287339887;3539.94690079649;3544.8936531904083;48738;49081;49084;49410;49713.25430118515;8570.95714521436;8614.827703445895;8662.566871656392;8688.39181257306;8766.649334026695;17831.643367132583;17934.820651793314;17999.19003644839;18048;18053.548661283312;7274.381677557361;7294.1247050354295;7371.226021267797;7436.1820199777985;7532.096148462219;4020.597940205956;4042.6563742082253;4063.837446502107;4082.3264161413626;4130.256553820281;3027.9394412111633;3065.892693755708;3075.9846200769302;3082.8612712427994;3085.382923415281;516.1019825503672;536.1528784520449;536.8147988943808;536.948989845964;537.2290762755458;469.6618434726991;477.2650118816983;494.04402481198997;506.1900958466498;553.7093026161298;7441.144268409094;7609.695612175562;7655.311022008046;7762.107357653942;7793.727219547289;387.469822061936;535.4163961282221;551.3549147497438;595.4432605513858;597.3817099302202;3542.75200735946;3568.839402226906;3698.6671199592165;3775.981120094382;3784.9810750946067;51755.741221293654;56849;59794;60075.39924600785;60226;51429.48570514322;52045.47954520406;52141.47858521366;52892.471075288755;54496.18255726167;48586;48646.5135348642;48833.5116648829;49320.506794932306;49390.50609493931;7529.397648188023;7595.696172153052;7683.270089341504;7953.2842044216295;7957.403194760301;33639.3272134551;33976.83011584926;34295.657043429244;34917.650823491436;34993;42633;44168;46624;47003.29495057509;47428.49429304211;9671.274654400808;9679.951600241953;9680.709578712649;9693.273004524548;9862.802743945225;10647.361158330525;10729;10851.348919064882;10939;11405.372704501;10307.330023548446;10340.896591033994;10630.468476575985;10928.836067458835;10933.125349972142;9432.85850712226;10023.248256380655;10181.694549163536;10275.84586231222;10307;8257.710980115668;8524.531150786519;8543.633018716975;8568.742937711879;8573.271271941705;8041.67833286662;8127.471714338549;8228.465149765247;8310.792230194293;8372.246497815226;7578.924210757822;7707.92292077072;7989.840203195788;8145.959270203848;8282.668693252443 20:08:01 INFO - 2015-11-24 20:08:01,335 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,856 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424481324__endTimestamp 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: Number of tests: 6 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,336 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5447.73 Median:5675.86 stddev:601.89 (10.6%) stddev-sans-first:576.72 20:08:01 INFO - 2015-11-24 20:08:01,337 DEBUG : BROWSER_OUTPUT: Values: 3174.8 3202.6 3244.3 3256.3 3262.2 5191.5 5342.9 5380.0 5391.5 5421.3 5343.8 5349.3 5373.9 5412.4 5464.3 4945.5 4996.6 5020.2 5057.1 5063.4 5021.9 5044.9 5046.1 5112.5 5121.0 5058.8 5063.1 5108.4 5131.1 5153.6 4786.3 4895.1 4933.1 5028.0 5033.0 4965.2 4965.9 4969.9 4989.1 5027.0 5141.4 5234.6 5280.1 5280.6 5291.6 5782.7 5825.7 5832.4 5858.6 5889.5 5909.0 5929.1 5929.4 5929.5 5933.2 5320.7 5422.2 5531.4 5537.1 5545.6 5901.4 5905.3 5967.2 5982.2 5983.9 5790.9 5814.7 5815.7 5818.4 5840.6 5713.7 5776.0 5864.0 5870.3 5926.7 5808.5 5889.7 5892.3 5897.1 5939.1 5840.5 5882.4 5886.4 5886.9 5889.9 4357.4 4445.2 4487.0 4541.9 4548.1 4066.2 4076.4 4097.0 4116.2 4134.6 5572.1 5582.4 5587.2 5587.6 5590.8 5702.0 5828.0 5836.2 5939.1 5949.2 5521.3 5617.1 5621.2 5626.7 5668.8 5130.2 5150.9 5154.7 5157.7 5172.3 5857.3 5977.3 6065.4 6122.5 6138.0 5744.2 5837.0 5923.9 5972.4 5985.1 6052.3 6061.0 6065.4 6066.4 6071.5 5707.0 5762.6 5765.3 5769.1 5781.5 5702.6 5771.7 5808.2 5837.6 5940.3 5753.4 5757.6 5760.5 5771.6 5775.3 5747.4 5752.6 5755.2 5759.7 5761.8 5429.0 5498.7 5556.8 5558.2 5671.4 5764.3 5787.7 5805.5 5817.1 5869.9 5624.2 5672.6 5675.9 5679.2 5683.3 20:08:01 INFO - 2015-11-24 20:08:01,337 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,337 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49386.95 Median:32415.43 stddev:86344.51 (266.4%) stddev-sans-first:86508.91 20:08:01 INFO - 2015-11-24 20:08:01,337 DEBUG : BROWSER_OUTPUT: Values: 564.4 580.5 593.7 607.5 623.2 32206.4 37834.6 38168.0 38242.2 38252.8 43009.8 43795.8 43889.6 43928.6 43963.6 7656.5 7758.8 8606.4 8839.3 10064.7 10185.3 10287.0 10300.6 10301.9 10304.0 10297.4 10338.1 10418.3 10426.5 10430.7 7644.4 7765.7 10264.8 10570.6 10573.4 10185.5 10307.8 10347.8 10348.3 10351.0 40423.8 40775.8 40804.6 40835.8 40894.8 2978.8 2991.5 3003.6 3028.7 3029.9 30043.0 30702.7 30829.1 30887.8 31260.1 3548.1 3560.1 3596.6 3679.3 3683.5 276395.0 277300.0 277916.0 278118.0 278382.0 55199.7 55900.0 61511.7 61838.7 61919.4 70427.0 72495.3 72886.3 72892.0 73003.6 13927.7 13941.5 13946.0 14033.1 14561.6 10265.4 10304.4 10305.2 10309.9 10310.0 20394.7 20864.9 20882.9 20908.2 20956.9 7971.4 8253.5 8263.8 8347.8 8360.6 943.4 948.8 995.8 1037.0 1134.0 28799.9 29179.3 29273.8 29625.3 29776.3 990.8 1017.0 1065.2 1087.7 1099.4 14430.3 14656.1 14967.3 15078.3 15233.2 386991.0 389962.0 390326.0 390520.0 394648.0 55343.7 56024.2 56028.4 56035.7 56120.0 1328.8 1334.9 1352.2 1356.1 1358.6 28346.0 28777.4 28807.0 29261.7 29265.3 45935.0 46476.8 46586.3 46602.8 46605.8 304515.0 307116.0 307536.0 307564.0 308238.0 28617.3 28964.4 32133.2 32643.8 32917.3 39149.4 39596.0 40166.4 40266.6 40611.4 32770.0 33706.0 34175.1 34341.5 36440.6 28650.0 33266.0 36490.3 37035.4 37348.8 33030.2 33227.5 33404.0 33882.0 34307.5 30895.7 32624.5 32719.2 32846.0 32889.9 24349.9 24461.3 24898.9 25118.4 25318.5 20:08:01 INFO - 2015-11-24 20:08:01,337 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,337 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26892.10 Median:200091.08 stddev:80644.26 (40.3%) stddev-sans-first:80845.86 20:08:01 INFO - 2015-11-24 20:08:01,338 DEBUG : BROWSER_OUTPUT: Values: 349.0 350.4 350.8 351.3 356.2 14350.1 14704.6 16247.4 16822.3 17824.3 15579.5 16394.1 16731.4 16748.2 16772.2 5142.9 5250.7 5257.6 5262.0 5266.7 4001.2 4107.0 5272.1 5299.4 5801.3 5819.2 5848.4 5880.9 5881.0 5902.9 5691.9 5798.1 5837.5 5842.8 5851.7 5071.9 5093.7 5114.9 5121.1 5125.5 18820.2 20252.4 20338.9 20683.2 21163.3 2163.6 2188.6 2213.0 2630.8 2633.9 13010.9 13091.5 13128.5 13359.5 13518.1 1720.9 1734.7 1753.6 1787.2 1857.9 54348.0 54678.7 55185.4 55327.7 59642.7 9484.5 9733.8 9795.5 9800.8 9879.7 12960.2 13005.1 13008.2 13010.1 13023.3 6913.8 6964.2 6981.8 7080.8 7115.1 6875.7 6898.7 7025.8 7031.4 7063.8 4326.0 4347.4 4359.2 4362.7 4387.8 1575.6 1604.2 1761.3 1797.5 1813.3 402.3 416.9 422.8 444.4 854.9 10040.4 10047.8 10581.7 10700.7 10794.6 439.3 851.2 874.8 951.5 980.8 7344.6 9369.3 9376.1 9399.8 9548.1 385417.0 396181.0 547353.0 547882.0 555651.0 77236.0 77624.0 80248.0 80491.0 82237.9 1096.4 1120.0 1125.7 1126.5 1129.0 15328.8 15358.8 15390.4 15652.8 15767.3 33883.3 35203.0 35315.5 35779.3 36437.3 45160.5 45425.0 46424.3 46812.5 46915.8 9374.3 9527.6 9533.7 9560.4 9586.5 10725.2 14946.5 17189.9 17214.5 17644.0 14144.9 14346.4 14425.4 14643.1 14717.9 13919.7 13974.2 14122.3 14219.4 14533.0 11626.7 11773.3 11909.8 13955.5 14129.0 9185.6 9261.0 9275.9 9276.6 9413.1 9816.0 10021.9 10516.6 13757.2 14001.9 20:08:01 INFO - 2015-11-24 20:08:01,338 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,338 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5412.03 Median:3539.21 stddev:2855.58 (80.7%) stddev-sans-first:2847.15 20:08:01 INFO - 2015-11-24 20:08:01,339 DEBUG : BROWSER_OUTPUT: Values: 1335.6 1693.5 1738.2 1766.0 1782.4 6319.4 7655.5 7823.8 7834.1 7845.5 7572.0 7804.7 7813.2 7818.6 7818.9 1924.7 1953.3 2047.3 2233.9 2265.7 2247.0 2253.6 2256.3 2256.8 2258.0 2157.4 2236.1 2237.1 2240.5 2241.7 1908.6 1956.9 2186.4 2233.8 2236.8 2090.9 2101.3 2108.1 2113.7 2135.5 7717.2 7846.3 7847.5 7854.8 7858.1 4554.8 4681.0 4701.4 4703.2 4708.6 2991.9 3012.7 3034.3 3055.7 3112.1 938.4 943.2 944.5 955.0 979.8 10693.8 10721.2 10722.5 10726.4 10741.7 4110.2 4191.8 4216.3 4224.9 4225.9 9163.3 9205.6 9228.2 9229.4 9234.6 4676.5 4687.5 4689.5 4707.9 4715.0 4241.8 4243.3 4271.8 4278.1 4280.1 3553.5 3569.9 3583.0 3604.2 3633.6 1192.3 1213.9 1218.5 1220.0 1230.4 958.4 1220.9 1235.5 1247.1 1277.8 6300.0 7176.7 7365.2 7374.4 7378.9 617.2 630.7 636.2 643.0 647.2 5453.2 5584.9 5621.9 5623.1 5640.4 9239.1 9297.1 9390.0 9408.4 9410.6 9127.4 9243.4 9292.5 9323.2 9402.1 7252.4 7292.6 7299.3 7308.2 7319.1 6959.4 7042.7 7054.8 7066.1 7098.6 8072.6 8282.6 8304.6 8306.5 8413.0 8446.0 8516.3 8537.7 8656.7 8660.4 6869.2 6909.2 6918.6 6926.7 6927.9 7316.0 7556.6 7570.2 7570.6 7617.5 7124.2 7191.7 7222.2 7278.4 7312.5 6996.2 6998.8 7069.3 7084.3 7151.2 5917.3 6185.6 7138.9 7155.2 7174.9 6279.7 6365.7 6411.7 6416.7 6435.4 6530.1 6609.7 6611.0 6636.4 6639.9 20:08:01 INFO - 2015-11-24 20:08:01,339 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,339 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5315.37 Median:6080.12 stddev:1601.52 (26.3%) stddev-sans-first:1561.64 20:08:01 INFO - 2015-11-24 20:08:01,340 DEBUG : BROWSER_OUTPUT: Values: 327.1 331.1 332.4 332.6 335.2 5989.3 6011.5 6052.0 6055.2 6061.0 5934.1 5937.8 5946.1 5952.1 5956.9 4620.7 4621.7 4623.4 4623.7 4629.4 4538.3 4557.5 4611.9 4612.3 4628.0 4555.9 4561.3 4576.4 4576.5 4579.3 4363.3 4544.8 4553.6 4554.5 4561.4 3891.9 3925.0 3943.9 4036.8 4040.8 5654.5 5678.0 5704.9 5704.9 5708.7 5013.3 5298.2 5300.1 5303.3 5306.6 6148.0 6198.4 6310.9 6314.4 6332.1 4417.5 4420.2 4422.3 4422.7 4425.6 6536.0 6539.0 6540.9 6541.5 6542.2 4442.5 4479.1 4535.8 4543.0 4550.7 4586.6 4588.4 4589.2 4589.8 4595.1 5290.2 5297.7 5298.4 5298.7 5308.0 6240.6 6338.7 6357.7 6358.1 6371.1 1616.8 1638.1 1640.5 1644.7 1646.0 1073.9 1077.8 1095.0 1095.9 1098.6 6102.1 6105.4 6114.8 6125.3 6131.8 6534.6 6536.5 6539.5 6546.2 6561.1 5986.7 6099.3 6118.1 6123.8 6126.4 3534.9 3552.0 3552.2 3555.8 3555.9 6778.2 6778.2 6779.0 6780.9 6781.7 6679.3 6680.6 6694.6 6696.8 6701.9 6597.1 6634.7 6696.8 6746.6 6832.6 6582.5 6596.2 6603.1 6616.3 6617.8 6512.2 6534.6 6539.5 6540.7 6542.8 6661.0 6838.0 6840.5 6840.7 6853.5 4374.1 4395.6 4397.5 4399.0 4437.1 6616.0 6619.1 6620.6 6628.9 6628.9 6273.8 6385.5 6400.7 6401.4 6419.0 6342.0 6345.0 6372.7 6377.9 6387.5 6289.2 6296.7 6299.8 6303.8 6305.4 6278.5 6450.5 6452.0 6467.5 6473.7 6238.0 6257.9 6258.6 6259.9 6273.9 20:08:01 INFO - 2015-11-24 20:08:01,340 DEBUG : BROWSER_OUTPUT: 20:08:01 INFO - 2015-11-24 20:08:01,340 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13553.50 Median:532.29 stddev:16429.81 (3086.6%) stddev-sans-first:16447.48 20:08:01 INFO - 2015-11-24 20:08:01,340 DEBUG : BROWSER_OUTPUT: Values: 683.6 739.4 769.2 811.5 815.2 14041.6 14440.5 14523.0 14524.6 14552.6 13676.1 13749.9 13801.7 14678.9 15122.8 700.1 834.8 849.5 850.1 850.7 700.2 704.8 705.3 820.6 824.8 851.5 852.7 859.4 859.6 860.8 853.7 853.8 853.8 854.5 867.2 530.0 534.6 538.1 642.0 642.0 13886.0 13894.9 13937.3 13953.4 14074.1 11144.2 11369.7 11412.9 11435.8 11480.6 9370.8 9574.3 9646.4 9799.4 9906.4 3465.0 3527.0 3538.8 3539.9 3544.9 48738.0 49081.0 49084.0 49410.0 49713.3 8571.0 8614.8 8662.6 8688.4 8766.6 17831.6 17934.8 17999.2 18048.0 18053.5 7274.4 7294.1 7371.2 7436.2 7532.1 4020.6 4042.7 4063.8 4082.3 4130.3 3027.9 3065.9 3076.0 3082.9 3085.4 516.1 536.2 536.8 536.9 537.2 469.7 477.3 494.0 506.2 553.7 7441.1 7609.7 7655.3 7762.1 7793.7 387.5 535.4 551.4 595.4 597.4 3542.8 3568.8 3698.7 3776.0 3785.0 51755.7 56849.0 59794.0 60075.4 60226.0 51429.5 52045.5 52141.5 52892.5 54496.2 48586.0 48646.5 48833.5 49320.5 49390.5 7529.4 7595.7 7683.3 7953.3 7957.4 33639.3 33976.8 34295.7 34917.7 34993.0 42633.0 44168.0 46624.0 47003.3 47428.5 9671.3 9680.0 9680.7 9693.3 9862.8 10647.4 10729.0 10851.3 10939.0 11405.4 10307.3 10340.9 10630.5 10928.8 10933.1 9432.9 10023.2 10181.7 10275.8 10307.0 8257.7 8524.5 8543.6 8568.7 8573.3 8041.7 8127.5 8228.5 8310.8 8372.2 7578.9 7707.9 7989.8 8146.0 8282.7 20:08:01 INFO - 2015-11-24 20:08:01,340 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:08:01 INFO - 2015-11-24 20:08:01,340 DEBUG : BROWSER_OUTPUT: 20:08:02 INFO - 2015-11-24 20:08:02,017 INFO : Browser exited with error code: 0 20:08:02 INFO - 2015-11-24 20:08:02,036 INFO : Completed test dromaeo_css (00:18:33) 20:08:02 INFO - 2015-11-24 20:08:02,036 INFO : Starting test kraken 20:08:02 INFO - 2015-11-24 20:08:02,036 DEBUG : operating with platform_type : linux_ 20:08:02 INFO - 2015-11-24 20:08:02,036 INFO : Initialising browser for kraken test... 20:08:02 INFO - 2015-11-24 20:08:02,042 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpcnx8v3/profile http://localhost:50702/getInfo.html 20:08:05 INFO - 2015-11-24 20:08:05,637 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:08:05 INFO - 2015-11-24 20:08:05,637 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:08:05 INFO - 2015-11-24 20:08:05,637 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:08:05 INFO - 2015-11-24 20:08:05,637 DEBUG : BROWSER_OUTPUT: __metrics 20:08:05 INFO - 2015-11-24 20:08:05,746 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] 20:08:07 INFO - 2015-11-24 20:08:07,111 INFO : Browser initialized. 20:08:07 INFO - 2015-11-24 20:08:07,112 INFO : Running cycle 1/1 for kraken test... 20:08:07 INFO - 2015-11-24 20:08:07,112 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpcnx8v3/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:08:18 INFO - 2015-11-24 20:08:18,862 DEBUG : BROWSER_OUTPUT: RSS: Main: 141029376 20:08:18 INFO - 2015-11-24 20:08:18,863 DEBUG : BROWSER_OUTPUT: 20:08:21 INFO - 2015-11-24 20:08:21,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50702/tests/kraken/driver.html?testName=audio-beat-detection) 20:08:21 INFO - 2015-11-24 20:08:21,885 DEBUG : BROWSER_OUTPUT: RSS: Main: 177913856 20:08:21 INFO - 2015-11-24 20:08:21,885 DEBUG : BROWSER_OUTPUT: 20:08:24 INFO - 2015-11-24 20:08:24,502 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50702/tests/kraken/driver.html?testName=audio-dft) 20:08:25 INFO - 2015-11-24 20:08:25,069 DEBUG : BROWSER_OUTPUT: RSS: Main: 165003264 20:08:25 INFO - 2015-11-24 20:08:25,069 DEBUG : BROWSER_OUTPUT: 20:08:27 INFO - 2015-11-24 20:08:27,911 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50702/tests/kraken/driver.html?testName=audio-fft) 20:08:28 INFO - 2015-11-24 20:08:28,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 150347776 20:08:28 INFO - 2015-11-24 20:08:28,472 DEBUG : BROWSER_OUTPUT: 20:08:30 INFO - 2015-11-24 20:08:30,444 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50702/tests/kraken/driver.html?testName=audio-oscillator) 20:08:31 INFO - 2015-11-24 20:08:31,004 DEBUG : BROWSER_OUTPUT: RSS: Main: 152571904 20:08:31 INFO - 2015-11-24 20:08:31,005 DEBUG : BROWSER_OUTPUT: 20:08:33 INFO - 2015-11-24 20:08:33,182 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50702/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:08:33 INFO - 2015-11-24 20:08:33,747 DEBUG : BROWSER_OUTPUT: RSS: Main: 153645056 20:08:33 INFO - 2015-11-24 20:08:33,748 DEBUG : BROWSER_OUTPUT: 20:08:36 INFO - 2015-11-24 20:08:36,667 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50702/tests/kraken/driver.html?testName=imaging-darkroom) 20:08:37 INFO - 2015-11-24 20:08:37,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 154038272 20:08:37 INFO - 2015-11-24 20:08:37,236 DEBUG : BROWSER_OUTPUT: 20:08:40 INFO - 2015-11-24 20:08:40,650 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50702/tests/kraken/driver.html?testName=imaging-desaturate) 20:08:41 INFO - 2015-11-24 20:08:41,231 DEBUG : BROWSER_OUTPUT: RSS: Main: 154947584 20:08:41 INFO - 2015-11-24 20:08:41,232 DEBUG : BROWSER_OUTPUT: 20:08:44 INFO - 2015-11-24 20:08:44,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50702/tests/kraken/driver.html?testName=json-parse-financial) 20:08:44 INFO - 2015-11-24 20:08:44,575 DEBUG : BROWSER_OUTPUT: RSS: Main: 154890240 20:08:44 INFO - 2015-11-24 20:08:44,575 DEBUG : BROWSER_OUTPUT: 20:08:46 INFO - 2015-11-24 20:08:46,124 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50702/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:08:46 INFO - 2015-11-24 20:08:46,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 168046592 20:08:46 INFO - 2015-11-24 20:08:46,690 DEBUG : BROWSER_OUTPUT: 20:08:48 INFO - 2015-11-24 20:08:48,504 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:08:49 INFO - 2015-11-24 20:08:49,097 DEBUG : BROWSER_OUTPUT: RSS: Main: 168722432 20:08:49 INFO - 2015-11-24 20:08:49,098 DEBUG : BROWSER_OUTPUT: 20:08:51 INFO - 2015-11-24 20:08:51,113 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:08:51 INFO - 2015-11-24 20:08:51,694 DEBUG : BROWSER_OUTPUT: RSS: Main: 172539904 20:08:51 INFO - 2015-11-24 20:08:51,694 DEBUG : BROWSER_OUTPUT: 20:08:54 INFO - 2015-11-24 20:08:54,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:08:54 INFO - 2015-11-24 20:08:54,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 181506048 20:08:54 INFO - 2015-11-24 20:08:54,661 DEBUG : BROWSER_OUTPUT: 20:08:57 INFO - 2015-11-24 20:08:57,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:08:57 INFO - 2015-11-24 20:08:57,803 DEBUG : BROWSER_OUTPUT: RSS: Main: 179539968 20:08:57 INFO - 2015-11-24 20:08:57,804 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,471 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50702/tests/kraken/driver.html?testName=ai-astar) 20:08:59 INFO - 2015-11-24 20:08:59,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 237219840 20:08:59 INFO - 2015-11-24 20:08:59,721 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,723 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:08:59 INFO - 2015-11-24 20:08:59,723 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:08:59 INFO - 2015-11-24 20:08:59,723 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:08:59 INFO - 2015-11-24 20:08:59,724 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:08:59 INFO - 2015-11-24 20:08:59,724 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;102;110;111;109;98;108;109;100 20:08:59 INFO - 2015-11-24 20:08:59,724 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;124;153;168;133;149;149;127;173;125 20:08:59 INFO - 2015-11-24 20:08:59,724 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;162;162;163;162;161;161;160;161 20:08:59 INFO - 2015-11-24 20:08:59,725 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;87;93;71;71;71;99;103;71 20:08:59 INFO - 2015-11-24 20:08:59,725 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;139;94;93;94;139;93;94;132 20:08:59 INFO - 2015-11-24 20:08:59,725 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;108;109;110;110;110;110;110;109;110 20:08:59 INFO - 2015-11-24 20:08:59,725 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;144;145;146;145;146;145;145;145 20:08:59 INFO - 2015-11-24 20:08:59,725 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;96;96;96;95;96;95 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;55;56;55;56;58;59;56;56;62 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;70;70;71;71;70;70;71;74 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;82;83;84;83;86;85;85;93;87 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;116;121;115;125;119;120;128;123;115 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;149;149;147;160;149;149;148;146;141 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;67;65;66;67;65;64;65;64;65 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:08:59 INFO - 2015-11-24 20:08:59,726 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,934 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424539722__endTimestamp 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: Number of tests: 14 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,727 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.50 Median:109.00 stddev:4.65 (4.3%) stddev-sans-first:4.84 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 102.0 110.0 111.0 109.0 98.0 108.0 109.0 100.0 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.00 Median:149.00 stddev:17.43 (11.7%) stddev-sans-first:18.43 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: Values: 149.0 124.0 153.0 168.0 133.0 149.0 149.0 127.0 173.0 125.0 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 20:08:59 INFO - 2015-11-24 20:08:59,728 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 162.0 162.0 163.0 162.0 161.0 161.0 160.0 161.0 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.90 Median:71.50 stddev:13.20 (18.5%) stddev-sans-first:13.61 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 87.0 93.0 71.0 71.0 71.0 99.0 103.0 71.0 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:93.00 stddev:20.98 (22.6%) stddev-sans-first:21.68 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 139.0 94.0 93.0 94.0 139.0 93.0 94.0 132.0 20:08:59 INFO - 2015-11-24 20:08:59,729 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,730 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.71 (0.6%) stddev-sans-first:0.73 20:08:59 INFO - 2015-11-24 20:08:59,730 DEBUG : BROWSER_OUTPUT: Values: 109.0 108.0 109.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 20:08:59 INFO - 2015-11-24 20:08:59,730 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,730 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 20:08:59 INFO - 2015-11-24 20:08:59,730 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 144.0 145.0 146.0 145.0 146.0 145.0 145.0 145.0 20:08:59 INFO - 2015-11-24 20:08:59,730 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,731 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 20:08:59 INFO - 2015-11-24 20:08:59,731 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 95.0 20:08:59 INFO - 2015-11-24 20:08:59,731 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,731 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.00 stddev:2.18 (3.9%) stddev-sans-first:2.29 20:08:59 INFO - 2015-11-24 20:08:59,731 DEBUG : BROWSER_OUTPUT: Values: 56.0 55.0 56.0 55.0 56.0 58.0 59.0 56.0 56.0 62.0 20:08:59 INFO - 2015-11-24 20:08:59,731 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:1.25 (1.8%) stddev-sans-first:1.30 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 70.0 71.0 71.0 70.0 70.0 71.0 74.0 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:3.27 (3.8%) stddev-sans-first:3.28 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: Values: 82.0 82.0 83.0 84.0 83.0 86.0 85.0 85.0 93.0 87.0 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.50 Median:122.00 stddev:4.38 (3.6%) stddev-sans-first:4.55 20:08:59 INFO - 2015-11-24 20:08:59,732 DEBUG : BROWSER_OUTPUT: Values: 123.0 116.0 121.0 115.0 125.0 119.0 120.0 128.0 123.0 115.0 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.80 Median:149.00 stddev:5.43 (3.6%) stddev-sans-first:4.97 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: Values: 140.0 149.0 149.0 147.0 160.0 149.0 149.0 148.0 146.0 141.0 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.07 (1.6%) stddev-sans-first:1.12 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 65.0 66.0 67.0 65.0 64.0 65.0 64.0 65.0 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:08:59 INFO - 2015-11-24 20:08:59,733 DEBUG : BROWSER_OUTPUT: 20:09:00 INFO - 2015-11-24 20:09:00,537 INFO : Browser exited with error code: 0 20:09:00 INFO - 2015-11-24 20:09:00,591 INFO : Completed test kraken (00:00:58) 20:09:00 INFO - 2015-11-24 20:09:00,591 INFO : Starting test v8_7 20:09:00 INFO - 2015-11-24 20:09:00,591 DEBUG : operating with platform_type : linux_ 20:09:00 INFO - 2015-11-24 20:09:00,592 INFO : Initialising browser for v8_7 test... 20:09:00 INFO - 2015-11-24 20:09:00,598 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3YcFBp/profile http://localhost:50702/getInfo.html 20:09:04 INFO - 2015-11-24 20:09:04,187 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:09:04 INFO - 2015-11-24 20:09:04,187 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:09:04 INFO - 2015-11-24 20:09:04,187 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 20:09:04 INFO - 2015-11-24 20:09:04,187 DEBUG : BROWSER_OUTPUT: __metrics 20:09:04 INFO - 2015-11-24 20:09:04,324 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] 20:09:05 INFO - 2015-11-24 20:09:05,418 INFO : Browser initialized. 20:09:05 INFO - 2015-11-24 20:09:05,419 INFO : Running cycle 1/1 for v8_7 test... 20:09:05 INFO - 2015-11-24 20:09:05,419 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3YcFBp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:09:17 INFO - 2015-11-24 20:09:17,160 DEBUG : BROWSER_OUTPUT: RSS: Main: 138899456 20:09:17 INFO - 2015-11-24 20:09:17,160 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,531 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50702/tests/v8_7/run.html (next: http://localhost:50702/tests/v8_7/run.html) 20:09:38 INFO - 2015-11-24 20:09:38,781 DEBUG : BROWSER_OUTPUT: RSS: Main: 540000256 20:09:38 INFO - 2015-11-24 20:09:38,781 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,781 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:09:38 INFO - 2015-11-24 20:09:38,781 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:09:38 INFO - 2015-11-24 20:09:38,781 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |0;Richards;164.66326362588507 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.70404984423675 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.60748723766307 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1127.3957158962796 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |5;Earley;849.0245971162002 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |6;Boyer;12268.292682926829 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |7;RegExp;26179.48717948718 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |8;Splay;595.2380952380952 20:09:38 INFO - 2015-11-24 20:09:38,782 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4873.78640776699 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: __startTimestamp1448424578780__endTimestamp 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: Number of tests: 10 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.66 Median:164.66 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: Values: 164.7 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,783 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.70 Median:194.70 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: Values: 194.7 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.61 Median:283.61 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: Values: 283.6 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: Values: 5191.7 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1127.40 Median:1127.40 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: Values: 1127.4 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,784 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:849.02 Median:849.02 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: Values: 849.0 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: Values: 12268.3 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26179.49 Median:26179.49 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: Values: 26179.5 20:09:38 INFO - 2015-11-24 20:09:38,785 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:595.24 Median:595.24 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: Values: 595.2 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4873.79 Median:4873.79 stddev:0.00 (0.0%) 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: Values: 4873.8 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:09:38 INFO - 2015-11-24 20:09:38,786 DEBUG : BROWSER_OUTPUT: 20:09:39 INFO - 2015-11-24 20:09:39,557 INFO : Browser exited with error code: 0 20:09:39 INFO - 2015-11-24 20:09:39,569 INFO : Completed test v8_7 (00:00:38) 20:09:39 INFO - 2015-11-24 20:09:39,807 INFO : Completed test suite (00:20:11) 20:09:39 INFO - 2015-11-24 20:09:39,807 DEBUG : Working with test: dromaeo_css 20:09:39 INFO - 2015-11-24 20:09:39,807 DEBUG : Generating results file: dromaeo_css 20:09:39 INFO - 2015-11-24 20:09:39,809 DEBUG : Working with test: kraken 20:09:39 INFO - 2015-11-24 20:09:39,809 DEBUG : Generating results file: kraken 20:09:39 INFO - 2015-11-24 20:09:39,809 INFO : javascript benchmark 20:09:39 INFO - 2015-11-24 20:09:39,809 DEBUG : Working with test: v8_7 20:09:39 INFO - 2015-11-24 20:09:39,809 DEBUG : Generating results file: v8_7 20:09:39 INFO - 2015-11-24 20:09:39,810 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:09:39 INFO - 2015-11-24 20:09:39,865 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 20:09:39 INFO - 2015-11-24 20:09:39,866 DEBUG : process_Request line: dromaeo_css 6889.52 graph.html#tests=[[72,201,35]] 20:09:39 INFO - 2015-11-24 20:09:39,866 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:09:39 INFO - 2015-11-24 20:09:39,895 DEBUG : process_Request line: kraken 1496.80 graph.html#tests=[[232,201,35]] 20:09:39 INFO - 2015-11-24 20:09:39,895 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 20:09:39 INFO - 2015-11-24 20:09:39,935 DEBUG : process_Request line: v8_7 20750.58 graph.html#tests=[[230,201,35]] 20:09:39 INFO - 2015-11-24 20:09:39,940 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3174.7936384135032, 3202.5836641236638, 3244.318693074454, 3256.3324518473714, 3262.151840521464, 5191.5327620514145, 5342.931413717262, 5379.999999999995, 5391.541718953894, 5421.349438067433, 5343.839684809462, 5349.3045904032515, 5373.946260537394, 5412.404635490103, 5464.3442786865635, 4945.505449455061, 4996.550310472066, 5020.246962955549, 5057.089723849724, 5063.443021267658, 5021.899562008758, 5044.899102017957, 5046.066477701618, 5112.539871411572, 5121.0, 5058.848234552933, 5063.088644044071, 5108.361454818148, 5131.0764062468925, 5153.6392452528335, 4786.2581299898575, 4895.090914543331, 4933.1120398328285, 5028.044671512401, 5033.043721692867, 4965.155923492979, 4965.851024469272, 4969.875753106165, 4989.101961646902, 5026.974865125651, 5141.383034035902, 5234.581233501311, 5280.128778751501, 5280.6039547034125, 5291.603129765283, 5782.681952492611, 5825.650460972312, 5832.387599302097, 5858.56060795442, 5889.528837692974, 5908.970455147697, 5929.080992446177, 5929.436703513159, 5929.466348028698, 5933.19901813254, 5320.707361095099, 5422.240886275925, 5531.363893152299, 5537.1140617501005, 5545.584081193927, 5901.380355062656, 5905.291365036223, 5967.224260846062, 5982.162497250389, 5983.940160598426, 5790.855228619234, 5814.709264536757, 5815.709214539256, 5818.389069147764, 5840.649561026352, 5713.657180569179, 5776.046952252916, 5863.970680146657, 5870.295564532198, 5926.674032928144, 5808.477237048686, 5889.734961926723, 5892.322382925932, 5897.144913987534, 5939.10913362999, 5840.532757379399, 5882.441168089024, 5886.352501224862, 5886.852828679317, 5889.911651325234, 4357.411749413852, 4445.199864024442, 4486.977565112219, 4541.931871021938, 4548.11311794197, 4066.1664358806374, 4076.449679293317, 4097.037196258877, 4116.238495878272, 4134.565870583606, 5572.08060669993, 5582.441755824385, 5587.217789509472, 5587.636803607753, 5590.8043218487155, 5702.0, 5828.0, 5836.182934389189, 5939.07944268639, 5949.167116603679, 5521.282233309725, 5617.073182924853, 5621.15682647598, 5626.662400255997, 5668.829935101953, 5130.204818253177, 5150.922736158961, 5154.716490593053, 5157.690538567698, 5172.250023746536, 5857.297124345106, 5977.282726072899, 6065.363136870565, 6122.479589234876, 6137.96931015351, 5744.167095771007, 5836.970815145897, 5923.940760592339, 5972.432618901197, 5985.1022346646605, 6052.273727152594, 6060.99993501085, 6065.363136870742, 6066.363031881765, 6071.514278857592, 5707.0, 5762.625429347079, 5765.336986246468, 5769.134629805562, 5781.4507621775865, 5702.600817942704, 5771.7402716877, 5808.186853840381, 5837.562182836219, 5940.257467816523, 5753.367129615655, 5757.596968212352, 5760.481556659921, 5771.59598828078, 5775.306963164448, 5747.36778954323, 5752.626079304831, 5755.165501002415, 5759.71201439918, 5761.827145185651, 5428.972855135699, 5498.725063746877, 5556.833295001156, 5558.166275058612, 5671.376148623731, 5764.308283006066, 5787.681677507778, 5805.506531944663, 5817.069268916953, 5869.941300586939, 5624.240727501735, 5672.5745569081655, 5675.858103547279, 5679.204911312502, 5683.318001839806], "unit": "ms", "name": "dojo.html", "value": 5408.55508924402}, {"lowerIsBetter": false, "replicates": [564.4347033762654, 580.4543728894841, 593.6675461741426, 607.4563265877039, 623.2365352443255, 32206.35587288253, 37834.621653783455, 38168.0, 38242.235155296876, 38252.80873595628, 43009.7849510752, 43795.78102109493, 43889.561104388944, 43928.56071439285, 43963.56036439635, 7656.464047516676, 7758.7672369828715, 8606.440581362207, 8839.337049721264, 10064.698059058202, 10185.33795303303, 10287.022732840405, 10300.639477618284, 10301.896981030206, 10304.0, 10297.382157070562, 10338.069573738361, 10418.27072104953, 10426.478676066205, 10430.68707938763, 7644.426667999895, 7765.72819951299, 10264.794704105876, 10570.577176912915, 10573.365598064142, 10185.541650625739, 10307.84538231927, 10347.793044139074, 10348.275620706554, 10351.0, 40423.7978810104, 40775.7961210195, 40804.59195408067, 40835.795821021005, 40894.79552602248, 2978.7616990640695, 2991.5064014437594, 3003.639563252402, 3028.6971302869756, 3029.9242518937203, 30043.0, 30702.692973070207, 30829.075127746204, 30887.845560772053, 31260.062198134092, 3548.1307079765625, 3560.12776869666, 3596.5504311961004, 3679.2825399047197, 3683.4658974448553, 276395.0, 277300.0, 277916.0, 278118.0, 278382.0, 55199.72400138054, 55900.0, 61511.6924415375, 61838.690806546576, 61919.38080619226, 70427.0, 72495.27504724886, 72886.271137289, 72892.0, 73003.63498182475, 13927.651808704863, 13941.512047078304, 13946.023778335622, 14033.087849289763, 14561.563153105424, 10265.384076955406, 10304.381737095799, 10305.175585953255, 10309.948450257702, 10310.0, 20394.69407958882, 20864.895675521526, 20882.895585521976, 20908.163673453197, 20956.895215523826, 7971.362291016705, 8253.546054967035, 8263.752087437266, 8347.833043339098, 8360.623771930277, 943.3726571829657, 948.8292107420731, 995.8307087794948, 1036.9792604147874, 1133.9943300283446, 28799.85600071986, 29179.27051823679, 29273.82414816933, 29625.25936851596, 29776.255593610334, 990.841465365538, 1016.9593216271414, 1065.163846380603, 1087.722630729159, 1099.4173088263233, 14430.278486075655, 14656.120632762068, 14967.326470308863, 15078.321475533627, 15233.23833808305, 386991.0, 389962.0, 390326.0, 390520.0, 394648.0, 55343.72328138333, 56024.1566180735, 56028.43971560314, 56035.71982140145, 56120.0, 1328.754180476615, 1334.9265790381428, 1352.1684164239118, 1356.1456282542133, 1358.626377746108, 28346.0, 28777.42445151127, 28807.0, 29261.70738292675, 29265.268368290963, 45935.0, 46476.767616161706, 46586.301205481264, 46602.766986164854, 46605.76697116493, 304515.0, 307116.0, 307536.0, 307564.0, 308238.0, 28617.284567885137, 28964.42071158523, 32133.196670083435, 32643.836780816895, 32917.341653166324, 39149.412758808074, 39596.0, 40166.397504036875, 40266.59733402746, 40611.390829136995, 32770.0, 33706.0, 34175.14562135967, 34341.484877726354, 36440.63559364479, 28650.0, 33266.0, 36490.270194595425, 37035.444468332455, 37348.81325593355, 33030.17424564309, 33227.50158747572, 33404.0, 33882.0, 34307.485387718705, 30895.691043089282, 32624.510632340058, 32719.182020448727, 32846.0, 32889.86215895959, 24349.87825060863, 24461.266162015167, 24898.875505622356, 25118.372040699127, 25318.493630126926], "unit": "ms", "name": "ext.html", "value": 18817.107272281442}, {"lowerIsBetter": false, "replicates": [348.9860405583777, 350.3815765174468, 350.80530305680344, 351.30967648570555, 356.1807841963483, 14350.067245629027, 14704.558863234093, 16247.431339903105, 16822.327106915724, 17824.287028518876, 15579.53261402157, 16394.09832459214, 16731.414400495985, 16748.246328915197, 16772.245248963794, 5142.897142057157, 5250.684958902459, 5257.579393648518, 5262.0265250928505, 5266.710330931797, 4001.1597564511344, 4107.014316564033, 5272.077386457374, 5299.364076310848, 5801.274840644919, 5819.214406055171, 5848.385919478457, 5880.9411905880825, 5880.97059514704, 5902.940970590282, 5691.886162276752, 5798.072308430631, 5837.50381217597, 5842.795502157425, 5851.7074146292725, 5071.898562028776, 5093.719845408501, 5114.872128196787, 5121.129408000649, 5125.538701516844, 18820.153093110843, 20252.392428227176, 20338.898305508526, 20683.172673093057, 21163.306935445282, 2163.642998905179, 2188.616992026389, 2212.9778702212934, 2630.842149471038, 2633.8814753336146, 13010.869891301156, 13091.476340946447, 13128.540501082416, 13359.465621375133, 13518.121322114031, 1720.8623310135158, 1734.722444408901, 1753.6054387762779, 1787.2225581871796, 1857.8978156201404, 54348.0, 54678.72660636711, 55185.448145518436, 55327.72336138334, 59642.70178649123, 9484.525773711286, 9733.805323893483, 9795.461249631338, 9800.754981125529, 9879.654212102541, 12960.157589756636, 13005.089643724848, 13008.154469959421, 13010.089293749535, 13023.348832558335, 6913.75801846943, 6964.164300283897, 6981.79054628362, 7080.822979425556, 7115.075040244735, 6875.690593923286, 6898.689558969859, 7025.754098606524, 7031.402330801837, 7063.788086357315, 4326.0, 4347.369631403492, 4359.17175736609, 4362.672799539983, 4387.824487020547, 1575.58247064528, 1604.1658337664583, 1761.3483011285907, 1797.532641513216, 1813.3200049981258, 402.3140545370137, 416.8916081818749, 422.780154319752, 444.3512471791221, 854.89291367679, 10040.447775372424, 10047.799044019224, 10581.735456613493, 10700.678979630467, 10794.568217271379, 439.27081045464377, 851.1871163039283, 874.7506960516222, 951.4529145741227, 980.8087422952456, 7344.632768361667, 9369.297302702325, 9376.109269619375, 9399.765005874908, 9548.140667339972, 385417.0, 396181.0, 547353.0, 547882.0, 555651.0, 77236.0, 77624.0, 80248.0, 80491.0, 82237.88810559448, 1096.4024606589428, 1119.9552017919193, 1125.7129431994952, 1126.4987080748988, 1129.0459561670539, 15328.770068448759, 15358.84641153619, 15390.384384624489, 15652.765208522109, 15767.290471928562, 33883.32233355368, 35203.0, 35315.47026794651, 35779.28441431105, 36437.27125457529, 45160.54839451695, 45425.0, 46424.30363544482, 46812.53187468082, 46915.765421174045, 9374.250059995047, 9527.571259293487, 9533.713988580354, 9560.426374417557, 9586.472743999147, 10725.195610329101, 14946.476873309382, 17189.91405043017, 17214.4896858836, 17644.0, 14144.939129565118, 14346.354414051602, 14425.42298308056, 14643.12141271527, 14717.852821471648, 13919.721605568035, 13974.161550307013, 14122.293885305487, 14219.431222750974, 14533.0, 11626.70933226676, 11773.293602383885, 11909.82135267989, 13955.511557095453, 14129.0, 9185.586648600696, 9260.953695231481, 9275.86086208694, 9276.6289348428, 9413.105754953269, 9815.950920245354, 10021.949890250502, 10516.631917882838, 13757.243351615758, 14001.92999035039], "unit": "ms", "name": "jquery.html", "value": 8429.101772934258}, {"lowerIsBetter": false, "replicates": [1335.559265442404, 1693.4919524142756, 1738.1743671755917, 1765.9558511037214, 1782.4385318624632, 6319.39965703258, 7655.4641175117695, 7823.804404889881, 7834.059912810457, 7845.490043147199, 7572.0, 7804.687812487494, 7813.179616140309, 7818.648160832762, 7818.921810781891, 1924.7497825282694, 1953.2675246782458, 2047.2629853252818, 2233.8994745236464, 2265.7054582904284, 2246.955060898781, 2253.6281513550243, 2256.255435706213, 2256.808171305441, 2257.954840903181, 2157.4174972757346, 2236.105557776886, 2237.071615279657, 2240.5182885679574, 2241.68616393705, 1908.5991941692191, 1956.8825870447747, 2186.3987403464016, 2233.8212942964687, 2236.7986881180773, 2090.874547527153, 2101.3065688322818, 2108.104055776289, 2113.7146485224453, 2135.487483004068, 7717.151113377524, 7846.3330616897065, 7847.489913155684, 7854.803629909298, 7858.0570331560575, 4554.795034223468, 4680.976595117003, 4701.435827700664, 4703.2004559225015, 4708.552687494683, 2991.925201869971, 3012.7439167670773, 3034.286942568495, 3055.709707577799, 3112.097491727396, 938.3994243684048, 943.1511639524414, 944.4522177137244, 954.990450095504, 979.8481235408452, 10693.78612427747, 10721.249512534127, 10722.517486713117, 10726.410047447387, 10741.677749667522, 4110.23960567296, 4191.811368488365, 4216.2832318505925, 4224.9155016899485, 4225.873223803291, 9163.266938644892, 9205.585748641328, 9228.215601673799, 9229.446233226028, 9234.630614775468, 4676.462206846189, 4687.507811679725, 4689.531046895351, 4707.929381059287, 4714.952850471452, 4241.809118589671, 4243.321068629005, 4271.807768650418, 4278.058827058042, 4280.122574872206, 3553.502509648651, 3569.928601427957, 3582.964170358263, 3604.18905746205, 3633.636636336398, 1192.3084610925641, 1213.8604060532975, 1218.4943248551929, 1220.0178856020812, 1230.4340003598265, 958.3914214473859, 1220.859601145862, 1235.5243231355812, 1247.1270110922392, 1277.8274932883942, 6299.96850015747, 7176.7129314826825, 7365.226651201654, 7374.410047196211, 7378.889316660255, 617.1822335405606, 630.6815058395573, 636.1761518833122, 643.0386572074684, 647.2136354329513, 5453.209284653783, 5584.916226256528, 5621.943780562224, 5623.0721930880945, 5640.379558248589, 9239.122283383067, 9297.07029297065, 9390.0, 9408.43549387039, 9410.623575056921, 9127.357075726433, 9243.39917905334, 9292.53537323131, 9323.20752736025, 9402.05979402068, 7252.419806415474, 7292.635368231568, 7299.306565876233, 7308.232635573295, 7319.085114360705, 6959.4432445405255, 7042.683079261343, 7054.788356349318, 7066.116735408074, 7098.645067746489, 8072.636731347221, 8282.585870706562, 8304.58477076156, 8306.460080094774, 8413.0, 8446.0, 8516.276116530164, 8537.701180458655, 8656.697015604424, 8660.437071590326, 6869.244383117953, 6909.239983601699, 6918.5502942308585, 6926.653667316715, 6927.89608155868, 7315.963420182865, 7556.622168891643, 7570.242975702386, 7570.621468926642, 7617.5048621839405, 7124.21633620291, 7191.712331506681, 7222.241664625139, 7278.417726581755, 7312.5246858954, 6996.160460744743, 6998.825029374307, 7069.328413800681, 7084.25615310385, 7151.177614574182, 5917.260342457193, 6185.628862268279, 7138.892916606359, 7155.24869888675, 7174.892376614459, 6279.748810047729, 6365.745370185141, 6411.743530258924, 6416.679166041773, 6435.388638079375, 6530.118434011348, 6609.702563384563, 6610.9669451652435, 6636.435902948304, 6639.867202656016], "unit": "ms", "name": "mootools.html", "value": 4378.676071907836}, {"lowerIsBetter": false, "replicates": [327.13962279205685, 331.134084369374, 332.35191376815214, 332.5743048897412, 335.1838273803289, 5989.281286245651, 6011.458968692817, 6052.0, 6055.2430946131735, 6060.969695151518, 5934.139549765292, 5937.7624895004255, 5946.137810017545, 5952.107183922414, 5956.940430595692, 4620.745858977772, 4621.722696638196, 4623.375844261023, 4623.69945953512, 4629.375034370368, 4538.273876179812, 4557.453105627313, 4611.93082103767, 4612.308153776927, 4628.0, 4555.931661025087, 4561.270196768518, 4576.405067341241, 4576.450825900896, 4579.335996280537, 4363.3018717005125, 4544.818207271689, 4553.567411095941, 4554.476235232941, 4561.384213131218, 3891.9221615567812, 3925.0, 3943.935137512884, 4036.7577945323374, 4040.838366465338, 5654.491095801356, 5677.977963966485, 5704.857378565527, 5704.914426283609, 5708.7145642718115, 5013.273075404082, 5298.15229563268, 5300.072487314705, 5303.310569625924, 5306.602004849613, 6147.985582378902, 6198.3801619838105, 6310.92714238576, 6314.400131987454, 6332.1135041094285, 4417.469903611555, 4420.160169567772, 4422.29243321067, 4422.7346359218545, 4425.557444255549, 6535.967320163416, 6539.0, 6540.93459065408, 6541.47668186544, 6542.247641521239, 4442.4669039715445, 4479.081788233404, 4535.841245556389, 4542.954570454319, 4550.7497087660495, 4586.610138138229, 4588.449386073626, 4589.196890544142, 4589.79345929434, 4595.058013107305, 5290.180022096504, 5297.682139071668, 5298.417174110922, 5298.735063246822, 5308.0, 6240.594361366497, 6338.714757835908, 6357.713902874381, 6358.078078678565, 6371.139896113966, 1616.8463995920604, 1638.1399765123406, 1640.4668482742918, 1644.6546225292823, 1646.037068315049, 1073.9194560408, 1077.7790552936629, 1094.9835752463719, 1095.8712526098132, 1098.5715570927346, 6102.084687296941, 6105.419985101408, 6114.755409783559, 6125.264968203844, 6131.816045518642, 6534.575252608565, 6536.542442029012, 6539.50953678476, 6546.24718157418, 6561.14705088345, 5986.670733109634, 6099.2680878294, 6118.082287656887, 6123.785667501616, 6126.448619624255, 3534.9218488360784, 3552.0409489437707, 3552.182997910443, 3555.822208889545, 3555.8933232003083, 6778.186617605918, 6778.2205046420295, 6779.0, 6780.932190678129, 6781.728730850809, 6679.298673639295, 6680.565763225375, 6694.564853284521, 6696.765613203612, 6701.932980670228, 6597.076409302702, 6634.668266586651, 6696.799096027127, 6746.628935408599, 6832.59004459724, 6582.539222254396, 6596.175478065242, 6603.141591593062, 6616.338366163345, 6617.8014659560295, 6512.153420055362, 6534.575252608565, 6539.509536784855, 6540.705668244846, 6542.803715888531, 6661.0, 6838.04267402574, 6840.452763778786, 6840.657967101525, 6853.520253582202, 4374.081442897027, 4395.560443955579, 4397.472303323621, 4399.0, 4437.068215674744, 6616.040674102132, 6619.0733297339375, 6620.635865027277, 6628.867422651617, 6628.867422651617, 6273.843153921006, 6385.521085918667, 6400.743970241138, 6401.423871851555, 6419.0, 6342.0, 6345.0, 6372.681365931778, 6377.8724425512155, 6387.5209359297305, 6289.213848268966, 6296.716647750771, 6299.811005669837, 6303.779367722108, 6305.4009869062365, 6278.497720182466, 6450.48396128318, 6451.967740161457, 6467.514936379696, 6473.741050357933, 6237.96881015592, 6257.937420625735, 6258.561900666909, 6259.874802504016, 6273.905891411541], "unit": "ms", "name": "prototype.html", "value": 4794.347525235248}, {"lowerIsBetter": false, "replicates": [683.5795985468935, 739.3789217057671, 769.2077160524661, 811.5455345006794, 815.2051749544195, 14041.648958776022, 14440.494582689607, 14523.0, 14524.63688407789, 14552.636184095403, 13676.111052781587, 13749.931250343734, 13801.654958626013, 14678.926605366958, 15122.773158402606, 700.1178515070997, 834.7871292820322, 849.4733265375445, 850.1116333431579, 850.7447765670274, 700.1912790726719, 704.7885634309712, 705.2735682247294, 820.6389188756958, 824.8432797768435, 851.5188918261163, 852.7058164933119, 859.4113032572676, 859.6432480520576, 860.8493513635088, 853.6713365354321, 853.7609469348592, 853.8164294676675, 854.4744981836158, 867.2065060469663, 529.9841004769863, 534.5963797332988, 538.1309185665161, 641.9743210271583, 642.0497663458061, 13885.958553108558, 13894.861051389458, 13937.303134843218, 13953.372098255602, 14074.08518446308, 11144.219904606682, 11369.658910232707, 11412.885871141349, 11435.828462573068, 11480.598179063692, 9370.812583748288, 9574.281928855295, 9646.372985755974, 9799.363041402286, 9906.356086854332, 3464.9826750866087, 3526.9647303526895, 3538.752287339887, 3539.94690079649, 3544.8936531904083, 48738.0, 49081.0, 49084.0, 49410.0, 49713.25430118515, 8570.95714521436, 8614.827703445895, 8662.566871656392, 8688.39181257306, 8766.649334026695, 17831.643367132583, 17934.820651793314, 17999.19003644839, 18048.0, 18053.548661283312, 7274.381677557361, 7294.1247050354295, 7371.226021267797, 7436.1820199777985, 7532.096148462219, 4020.597940205956, 4042.6563742082253, 4063.837446502107, 4082.3264161413626, 4130.256553820281, 3027.9394412111633, 3065.892693755708, 3075.9846200769302, 3082.8612712427994, 3085.382923415281, 516.1019825503672, 536.1528784520449, 536.8147988943808, 536.948989845964, 537.2290762755458, 469.6618434726991, 477.2650118816983, 494.04402481198997, 506.1900958466498, 553.7093026161298, 7441.144268409094, 7609.695612175562, 7655.311022008046, 7762.107357653942, 7793.727219547289, 387.469822061936, 535.4163961282221, 551.3549147497438, 595.4432605513858, 597.3817099302202, 3542.75200735946, 3568.839402226906, 3698.6671199592165, 3775.981120094382, 3784.9810750946067, 51755.741221293654, 56849.0, 59794.0, 60075.39924600785, 60226.0, 51429.48570514322, 52045.47954520406, 52141.47858521366, 52892.471075288755, 54496.18255726167, 48586.0, 48646.5135348642, 48833.5116648829, 49320.506794932306, 49390.50609493931, 7529.397648188023, 7595.696172153052, 7683.270089341504, 7953.2842044216295, 7957.403194760301, 33639.3272134551, 33976.83011584926, 34295.657043429244, 34917.650823491436, 34993.0, 42633.0, 44168.0, 46624.0, 47003.29495057509, 47428.49429304211, 9671.274654400808, 9679.951600241953, 9680.709578712649, 9693.273004524548, 9862.802743945225, 10647.361158330525, 10729.0, 10851.348919064882, 10939.0, 11405.372704501, 10307.330023548446, 10340.896591033994, 10630.468476575985, 10928.836067458835, 10933.125349972142, 9432.85850712226, 10023.248256380655, 10181.694549163536, 10275.84586231222, 10307.0, 8257.710980115668, 8524.531150786519, 8543.633018716975, 8568.742937711879, 8573.271271941705, 8041.67833286662, 8127.471714338549, 8228.465149765247, 8310.792230194293, 8372.246497815226, 7578.924210757822, 7707.92292077072, 7989.840203195788, 8145.959270203848, 8282.668693252443], "unit": "ms", "name": "yui.html", "value": 5937.529010473401}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 102.0, 110.0, 111.0, 109.0, 98.0, 108.0, 109.0, 100.0], "unit": "ms", "name": "ai-astar", "value": 106.5}, {"lowerIsBetter": true, "replicates": [149.0, 124.0, 153.0, 168.0, 133.0, 149.0, 149.0, 127.0, 173.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.0}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 162.0, 162.0, 163.0, 162.0, 161.0, 161.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.3}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 87.0, 93.0, 71.0, 71.0, 71.0, 99.0, 103.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 139.0, 94.0, 93.0, 94.0, 139.0, 93.0, 94.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 144.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 56.0, 55.0, 56.0, 58.0, 59.0, 56.0, 56.0, 62.0], "unit": "ms", "name": "json-parse-financial", "value": 56.9}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 70.0, 71.0, 71.0, 70.0, 70.0, 71.0, 74.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.7}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 83.0, 84.0, 83.0, 86.0, 85.0, 85.0, 93.0, 87.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.0}, {"lowerIsBetter": true, "replicates": [123.0, 116.0, 121.0, 115.0, 125.0, 119.0, 120.0, 128.0, 123.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.5}, {"lowerIsBetter": true, "replicates": [140.0, 149.0, 149.0, 147.0, 160.0, 149.0, 149.0, 148.0, 146.0, 141.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 147.8}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 65.0, 66.0, 67.0, 65.0, 64.0, 65.0, 64.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.4}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.66326362588507], "unit": "score", "name": "Richards", "value": 214.38904599999995}, {"lowerIsBetter": false, "replicates": [194.70404984423675], "unit": "score", "name": "DeltaBlue", "value": 339.58204800000004}, {"lowerIsBetter": false, "replicates": [283.60748723766307], "unit": "score", "name": "Encrypt", "value": 938.5542060000004}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1127.3957158962796], "unit": "score", "name": "RayTrace", "value": 656.370243}, {"lowerIsBetter": false, "replicates": [849.0245971162002], "unit": "score", "name": "Earley", "value": 784.9749020979021}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [26179.48717948718], "unit": "score", "name": "RegExp", "value": 34.79766405484819}, {"lowerIsBetter": false, "replicates": [595.2380952380952], "unit": "score", "name": "Splay", "value": 136.90488}, {"lowerIsBetter": false, "replicates": [4873.78640776699], "unit": "score", "name": "NavierStokes", "value": 304.48605577689233}], "name": "v8_7"}]} 20:09:39 INFO - RETURN: dromaeo_css: 6889.52 20:09:39 INFO - RETURN: kraken: 1496.80 20:09:39 INFO - RETURN: v8_7: 20750.58 20:09:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "20750.58"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1496.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6889.52"}}} 20:09:39 INFO - Return code: 0 20:09:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 20:09:40 INFO - # TBPL SUCCESS # 20:09:40 INFO - Running post-action listener: _resource_record_post_action 20:09:40 INFO - Running post-run listener: _resource_record_post_run 20:09:41 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 0; Write bytes: 510496768; Read time: 0; Write time: 563100 20:09:41 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 242417664; Read time: 0; Write time: 368684 20:09:41 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 0; Write bytes: 268079104; Read time: 0; Write time: 194416 20:09:41 INFO - Running post-run listener: _upload_blobber_files 20:09:41 INFO - Blob upload gear active. 20:09:41 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 20:09:41 INFO - Copying logs to upload dir... 20:09:41 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.097802 ========= master_lag: 1.94 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2015-11-24 20:09:42.770609) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 20:09:42.774439) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009953 build_url: 'https://queue.taskcluster.net/v1/task/LlY8vIQ2QzeS5xT8TghTkQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 20:09:42.797326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 20:09:42.797642) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1448418958.803742-2002997335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 20:09:42.849507) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 20:09:42.849981) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 20:09:42.850462) ========= ========= Total master_lag: 2.12 =========