builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-001 starttime: 1445688009.22 results: success (0) buildid: 20151024034732 builduid: 56f3cce0f0db49969d92bf85465e3e89 revision: 05d85d9bbce4660987a69574cc40b19157e42fea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.221448) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.221973) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.222270) ========= bash -c pwd in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006234 basedir: '/builds/slave/test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.271327) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.271614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.310257) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.310637) ========= rm -rf properties in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018026 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.359601) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.359884) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.360222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.360505) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-10-24 05:00:09-- 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.7M=0s 2015-10-24 05:00:09 (73.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.156999 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.633070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.633346) ========= rm -rf scripts in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.099282 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.746640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:09.747030) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 05d85d9bbce4660987a69574cc40b19157e42fea --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 05d85d9bbce4660987a69574cc40b19157e42fea --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-10-24 05:00:09,783 truncating revision to first 12 chars 2015-10-24 05:00:09,783 Setting DEBUG logging. 2015-10-24 05:00:09,783 attempt 1/10 2015-10-24 05:00:09,783 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/05d85d9bbce4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-24 05:00:10,203 unpacking tar archive at: mozilla-central-05d85d9bbce4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.683274 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:10.445337) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:10.445601) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:10.455307) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:10.455553) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-24 05:00:10.455937) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-10-24 05:00:10.456217) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:00:10 INFO - MultiFileLogger online at 20151024 05:00:10 in /builds/slave/test 05:00:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:00:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:00:10 INFO - {'append_to_log': False, 05:00:10 INFO - 'base_work_dir': '/builds/slave/test', 05:00:10 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:00:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:00:10 INFO - 'branch': 'Firefox-Non-PGO', 05:00:10 INFO - 'buildbot_json_path': 'buildprops.json', 05:00:10 INFO - 'config_files': ('talos/linux_config.py',), 05:00:10 INFO - 'default_actions': ('clobber', 05:00:10 INFO - 'read-buildbot-config', 05:00:10 INFO - 'download-and-extract', 05:00:10 INFO - 'populate-webroot', 05:00:10 INFO - 'create-virtualenv', 05:00:10 INFO - 'install', 05:00:10 INFO - 'run-tests'), 05:00:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:00:10 INFO - 'download_minidump_stackwalk': True, 05:00:10 INFO - 'download_symbols': 'ondemand', 05:00:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:00:10 INFO - 'tooltool.py': '/tools/tooltool.py', 05:00:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:00:10 INFO - '/tools/misc-python/virtualenv.py')}, 05:00:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:00:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:00:10 INFO - 'installer_path': 'installer.exe', 05:00:10 INFO - 'log_level': 'info', 05:00:10 INFO - 'log_name': 'talos', 05:00:10 INFO - 'log_to_console': True, 05:00:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:00:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:00:10 INFO - 'opt_config_files': (), 05:00:10 INFO - 'pip_index': False, 05:00:10 INFO - 'sps_profile': False, 05:00:10 INFO - 'sps_profile_interval': 0, 05:00:10 INFO - 'suite': 'dromaeojs', 05:00:10 INFO - 'system_bits': '64', 05:00:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:00:10 INFO - 'title': 'talos-linux64-ix-001', 05:00:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:00:10 INFO - 'use_talos_json': True, 05:00:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:00:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:00:10 INFO - 'work_dir': 'build'} 05:00:10 INFO - ##### 05:00:10 INFO - ##### Running clobber step. 05:00:10 INFO - ##### 05:00:10 INFO - Running pre-action listener: _resource_record_pre_action 05:00:10 INFO - Running main action method: clobber 05:00:10 INFO - rmtree: /builds/slave/test/build 05:00:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:00:11 INFO - Running post-action listener: _resource_record_post_action 05:00:11 INFO - ##### 05:00:11 INFO - ##### Running read-buildbot-config step. 05:00:11 INFO - ##### 05:00:11 INFO - Running pre-action listener: _resource_record_pre_action 05:00:11 INFO - Running main action method: read_buildbot_config 05:00:11 INFO - Using buildbot properties: 05:00:11 INFO - { 05:00:11 INFO - "properties": { 05:00:11 INFO - "buildnumber": 314, 05:00:11 INFO - "product": "firefox", 05:00:11 INFO - "script_repo_revision": "production", 05:00:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:00:11 INFO - "repository": "", 05:00:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 05:00:11 INFO - "buildid": "20151024034732", 05:00:11 INFO - "slavename": "talos-linux64-ix-001", 05:00:11 INFO - "pgo_build": "False", 05:00:11 INFO - "basedir": "/builds/slave/test", 05:00:11 INFO - "project": "", 05:00:11 INFO - "platform": "ubuntu64_hw", 05:00:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:00:11 INFO - "slavebuilddir": "test", 05:00:11 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 05:00:11 INFO - "branch": "mozilla-central", 05:00:11 INFO - "repo_path": "mozilla-central", 05:00:11 INFO - "stage_platform": "linux64", 05:00:11 INFO - "builduid": "56f3cce0f0db49969d92bf85465e3e89", 05:00:11 INFO - "revision": "05d85d9bbce4660987a69574cc40b19157e42fea" 05:00:11 INFO - }, 05:00:11 INFO - "sourcestamp": { 05:00:11 INFO - "repository": "", 05:00:11 INFO - "hasPatch": false, 05:00:11 INFO - "project": "", 05:00:11 INFO - "branch": "mozilla-central-linux64-talos", 05:00:11 INFO - "changes": [ 05:00:11 INFO - { 05:00:11 INFO - "category": null, 05:00:11 INFO - "files": [ 05:00:11 INFO - { 05:00:11 INFO - "url": null, 05:00:11 INFO - "name": "https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2" 05:00:11 INFO - } 05:00:11 INFO - ], 05:00:11 INFO - "repository": "", 05:00:11 INFO - "rev": "05d85d9bbce4660987a69574cc40b19157e42fea", 05:00:11 INFO - "who": "ffxbld", 05:00:11 INFO - "when": 1445687962, 05:00:11 INFO - "number": 6569990, 05:00:11 INFO - "comments": "No bug, Automated blocklist update from host bld-linux64-spot-508 - a=blocklist-update", 05:00:11 INFO - "project": "", 05:00:11 INFO - "at": "Sat 24 Oct 2015 04:59:22", 05:00:11 INFO - "branch": "mozilla-central-linux64-talos", 05:00:11 INFO - "revlink": "", 05:00:11 INFO - "properties": [ 05:00:11 INFO - [ 05:00:11 INFO - "buildid", 05:00:11 INFO - "20151024034732", 05:00:11 INFO - "Change" 05:00:11 INFO - ], 05:00:11 INFO - [ 05:00:11 INFO - "builduid", 05:00:11 INFO - "56f3cce0f0db49969d92bf85465e3e89", 05:00:11 INFO - "Change" 05:00:11 INFO - ], 05:00:11 INFO - [ 05:00:11 INFO - "pgo_build", 05:00:11 INFO - "False", 05:00:11 INFO - "Change" 05:00:11 INFO - ] 05:00:11 INFO - ], 05:00:11 INFO - "revision": "05d85d9bbce4660987a69574cc40b19157e42fea" 05:00:11 INFO - } 05:00:11 INFO - ], 05:00:11 INFO - "revision": "05d85d9bbce4660987a69574cc40b19157e42fea" 05:00:11 INFO - } 05:00:11 INFO - } 05:00:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2. 05:00:11 INFO - Running post-action listener: _resource_record_post_action 05:00:11 INFO - ##### 05:00:11 INFO - ##### Running download-and-extract step. 05:00:11 INFO - ##### 05:00:11 INFO - Running pre-action listener: _resource_record_pre_action 05:00:11 INFO - Running main action method: download_and_extract 05:00:11 INFO - mkdir: /builds/slave/test/build/tests 05:00: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')]} 05:00:11 INFO - https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:00:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/test_packages.json 05:00:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/test_packages.json 05:00:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:00:12 INFO - Downloaded 1302 bytes. 05:00:12 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:00:12 INFO - Using the following test package requirements: 05:00:12 INFO - {u'common': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 05:00:12 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'firefox-44.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:00:12 INFO - u'jittest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'jsshell-linux-x86_64.zip'], 05:00:12 INFO - u'mochitest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'firefox-44.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:00:12 INFO - u'mozbase': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 05:00:12 INFO - u'reftest': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'firefox-44.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:00:12 INFO - u'talos': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:00:12 INFO - u'web-platform': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'firefox-44.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:00:12 INFO - u'webapprt': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'], 05:00:12 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 05:00:12 INFO - u'firefox-44.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:00:12 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:00:12 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')]} 05:00:12 INFO - https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:00:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:00:14 INFO - Downloaded 22680719 bytes. 05:00:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 05:00:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:00:15 INFO - Return code: 0 05:00:15 INFO - Downloading packages: [u'firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:00:15 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')]} 05:00:15 INFO - https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:00:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:00:16 INFO - Downloaded 22680719 bytes. 05:00:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 05:00:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip 05:00:16 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:00:17 INFO - Return code: 0 05:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:17 INFO - https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:00:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 05:00:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 05:00:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 05:00:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:00:19 INFO - Downloaded 11131307 bytes. 05:00:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 05:00:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip 05:00:19 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:00:19 INFO - Return code: 0 05:00:19 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')]} 05:00:19 INFO - https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:00:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 05:00:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 05:00:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 05:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:00:22 INFO - Downloaded 55650948 bytes. 05:00:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 05:00:22 INFO - mkdir: /builds/slave/test/properties 05:00:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:00:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:00:22 INFO - Contents: 05:00:22 INFO - build_url:https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 05:00:22 INFO - Running post-action listener: _resource_record_post_action 05:00:22 INFO - Running post-action listener: set_extra_try_arguments 05:00:22 INFO - ##### 05:00:22 INFO - ##### Running populate-webroot step. 05:00:22 INFO - ##### 05:00:22 INFO - Running pre-action listener: _resource_record_pre_action 05:00:22 INFO - Running main action method: populate_webroot 05:00:22 INFO - Guessing talos json url... 05:00:22 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')]} 05:00:22 INFO - https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt matches https://queue.taskcluster.net 05:00:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt 05:00:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt 05:00:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt to /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt 05:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.txt', 'file_name': '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt'}, attempt #1 05:00:22 INFO - Downloaded 99 bytes. 05:00:22 INFO - Reading from file /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.txt 05:00:22 INFO - Contents: 05:00:22 INFO - 20151024034732 05:00:22 INFO - https://hg.mozilla.org/mozilla-central/rev/05d85d9bbce4660987a69574cc40b19157e42fea 05:00:22 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')]} 05:00:22 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/05d85d9bbc/testing/talos/talos.json 05:00:22 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/05d85d9bbc/testing/talos/talos.json to /builds/slave/test/build/talos.json 05:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/05d85d9bbc/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1 05:00:23 INFO - Downloaded 4264 bytes. 05:00:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:00:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:00:23 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:00:23 INFO - '../fennec_ids.txt'], 05:00:23 INFO - 'tests': ['tcheck2']}, 05:00:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:00:23 INFO - '--tppagecycles', 05:00:23 INFO - '7'], 05:00:23 INFO - 'tests': ['tsvgm']}}, 05:00:23 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:00:23 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:00:23 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:00:23 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:00:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'tests': ['damp', 'tps']}, 05:00:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['damp', 'tps']}, 05:00:23 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:00:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:00:23 INFO - 'other': {'tests': ['a11yr', 05:00:23 INFO - 'ts_paint', 05:00:23 INFO - 'tpaint', 05:00:23 INFO - 'sessionrestore', 05:00:23 INFO - 'sessionrestore_no_auto_restore']}, 05:00:23 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['a11yr', 05:00:23 INFO - 'ts_paint', 05:00:23 INFO - 'tpaint', 05:00:23 INFO - 'sessionrestore', 05:00:23 INFO - 'sessionrestore_no_auto_restore']}, 05:00:23 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['a11yr', 05:00:23 INFO - 'ts_paint', 05:00:23 INFO - 'tpaint', 05:00:23 INFO - 'sessionrestore', 05:00:23 INFO - 'sessionrestore_no_auto_restore']}, 05:00:23 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['a11yr', 05:00:23 INFO - 'ts_paint', 05:00:23 INFO - 'tpaint', 05:00:23 INFO - 'sessionrestore', 05:00:23 INFO - 'sessionrestore_no_auto_restore']}, 05:00:23 INFO - 'other_l64': {'tests': ['a11yr', 05:00:23 INFO - 'ts_paint', 05:00:23 INFO - 'tpaint', 05:00:23 INFO - 'sessionrestore', 05:00:23 INFO - 'sessionrestore_no_auto_restore']}, 05:00:23 INFO - 'other_nol64': {'tests': ['a11yr', 05:00:23 INFO - 'ts_paint', 05:00:23 INFO - 'tpaint', 05:00:23 INFO - 'sessionrestore', 05:00:23 INFO - 'sessionrestore_no_auto_restore']}, 05:00:23 INFO - 'svgr': {'tests': ['tsvgx', 05:00:23 INFO - 'tsvgr_opacity', 05:00:23 INFO - 'tart', 05:00:23 INFO - 'tscrollx', 05:00:23 INFO - 'cart']}, 05:00:23 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['tsvgx', 05:00:23 INFO - 'tsvgr_opacity', 05:00:23 INFO - 'tart', 05:00:23 INFO - 'tscrollx', 05:00:23 INFO - 'cart']}, 05:00:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'tests': ['tp5o']}, 05:00:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'talos_options': ['--e10s'], 05:00:23 INFO - 'tests': ['tp5o']}, 05:00:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'talos_options': ['--xperf_path', 05:00:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:23 INFO - 'tests': ['tp5n']}, 05:00:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:23 INFO - 'talos_options': ['--e10s', 05:00:23 INFO - '--xperf_path', 05:00:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 05:00:23 INFO - 'C:/slave/talos-data/talos/xperf.config'], 05:00:23 INFO - 'tests': ['tp5n']}}, 05:00:23 INFO - 'talos.zip': {'path': '', 05:00:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:00:23 INFO - Running post-action listener: _resource_record_post_action 05:00:23 INFO - ##### 05:00:23 INFO - ##### Running create-virtualenv step. 05:00:23 INFO - ##### 05:00:23 INFO - Running pre-action listener: _resource_record_pre_action 05:00:23 INFO - Running main action method: create_virtualenv 05:00:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:00:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:00:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:00:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:00:23 INFO - Using real prefix '/usr' 05:00:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:00:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:26 INFO - Installing pip.................done. 05:00:26 INFO - Return code: 0 05:00:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 05:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 05:00:26 INFO - Using env: {'DISPLAY': ':0', 05:00:26 INFO - 'HOME': '/home/cltbld', 05:00:26 INFO - 'LANG': 'en_US.UTF-8', 05:00:26 INFO - 'LANGUAGE': 'en_US:en', 05:00:26 INFO - 'LOGNAME': 'cltbld', 05:00:26 INFO - 'MAIL': '/var/mail/cltbld', 05:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:26 INFO - 'MOZ_NO_REMOTE': '1', 05:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:26 INFO - 'NO_EM_RESTART': '1', 05:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:26 INFO - 'PWD': '/builds/slave/test', 05:00:26 INFO - 'SHELL': '/bin/bash', 05:00:26 INFO - 'SHLVL': '1', 05:00:26 INFO - 'TERM': 'linux', 05:00:26 INFO - 'TMOUT': '86400', 05:00:26 INFO - 'USER': 'cltbld', 05:00:26 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:26 INFO - '_': '/tools/buildbot/bin/python'} 05:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:27 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 05:00:27 INFO - Cleaning up... 05:00:27 INFO - Return code: 0 05:00:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:00:27 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')]} 05:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:27 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')]} 05:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 05:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 05:00:27 INFO - Using env: {'DISPLAY': ':0', 05:00:27 INFO - 'HOME': '/home/cltbld', 05:00:27 INFO - 'LANG': 'en_US.UTF-8', 05:00:27 INFO - 'LANGUAGE': 'en_US:en', 05:00:27 INFO - 'LOGNAME': 'cltbld', 05:00:27 INFO - 'MAIL': '/var/mail/cltbld', 05:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:27 INFO - 'MOZ_NO_REMOTE': '1', 05:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:27 INFO - 'NO_EM_RESTART': '1', 05:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:27 INFO - 'PWD': '/builds/slave/test', 05:00:27 INFO - 'SHELL': '/bin/bash', 05:00:27 INFO - 'SHLVL': '1', 05:00:27 INFO - 'TERM': 'linux', 05:00:27 INFO - 'TMOUT': '86400', 05:00:27 INFO - 'USER': 'cltbld', 05:00:27 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:27 INFO - '_': '/tools/buildbot/bin/python'} 05:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:27 INFO - Downloading/unpacking psutil>=0.7.1 05:00:27 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 05:00:27 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 05:00:27 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 05:00:27 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 05:00:27 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 05:00:27 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 05:00:28 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:00:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:29 INFO - Installing collected packages: psutil 05:00:29 INFO - Running setup.py install for psutil 05:00:29 INFO - building 'psutil._psutil_linux' extension 05:00:29 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 05:00:30 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 05:00:30 INFO - building 'psutil._psutil_posix' extension 05:00:30 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 05:00:30 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 05:00:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:30 INFO - Successfully installed psutil 05:00:30 INFO - Cleaning up... 05:00:30 INFO - Return code: 0 05:00:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:00:30 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')]} 05:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:30 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')]} 05:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 05:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 05:00:30 INFO - Using env: {'DISPLAY': ':0', 05:00:30 INFO - 'HOME': '/home/cltbld', 05:00:30 INFO - 'LANG': 'en_US.UTF-8', 05:00:30 INFO - 'LANGUAGE': 'en_US:en', 05:00:30 INFO - 'LOGNAME': 'cltbld', 05:00:30 INFO - 'MAIL': '/var/mail/cltbld', 05:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:30 INFO - 'MOZ_NO_REMOTE': '1', 05:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:30 INFO - 'NO_EM_RESTART': '1', 05:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:30 INFO - 'PWD': '/builds/slave/test', 05:00:30 INFO - 'SHELL': '/bin/bash', 05:00:30 INFO - 'SHLVL': '1', 05:00:30 INFO - 'TERM': 'linux', 05:00:30 INFO - 'TMOUT': '86400', 05:00:30 INFO - 'USER': 'cltbld', 05:00:30 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:30 INFO - '_': '/tools/buildbot/bin/python'} 05:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:00:30 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 05:00:30 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 05:00:30 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 05:00:30 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 05:00:30 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 05:00:30 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 05:00:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:00:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:00:32 INFO - Installing collected packages: mozsystemmonitor 05:00:32 INFO - Running setup.py install for mozsystemmonitor 05:00:32 INFO - Successfully installed mozsystemmonitor 05:00:32 INFO - Cleaning up... 05:00:32 INFO - Return code: 0 05:00:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00:32 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')]} 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 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')]} 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 05:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 05:00:32 INFO - Using env: {'DISPLAY': ':0', 05:00:32 INFO - 'HOME': '/home/cltbld', 05:00:32 INFO - 'LANG': 'en_US.UTF-8', 05:00:32 INFO - 'LANGUAGE': 'en_US:en', 05:00:32 INFO - 'LOGNAME': 'cltbld', 05:00:32 INFO - 'MAIL': '/var/mail/cltbld', 05:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:32 INFO - 'MOZ_NO_REMOTE': '1', 05:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:32 INFO - 'NO_EM_RESTART': '1', 05:00:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:32 INFO - 'PWD': '/builds/slave/test', 05:00:32 INFO - 'SHELL': '/bin/bash', 05:00:32 INFO - 'SHLVL': '1', 05:00:32 INFO - 'TERM': 'linux', 05:00:32 INFO - 'TMOUT': '86400', 05:00:32 INFO - 'USER': 'cltbld', 05:00:32 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:32 INFO - '_': '/tools/buildbot/bin/python'} 05:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:00:32 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 05:00:32 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 05:00:32 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 05:00:32 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 05:00:32 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 05:00:32 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 05:00:34 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 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 05:00:34 INFO - Downloading docopt-0.6.1.tar.gz 05:00:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:35 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:35 INFO - Running setup.py install for blobuploader 05:00:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:35 INFO - Running setup.py install for requests 05:00:35 INFO - Running setup.py install for docopt 05:00:35 INFO - Successfully installed blobuploader requests docopt 05:00:35 INFO - Cleaning up... 05:00:35 INFO - Return code: 0 05:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:35 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')]} 05:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:35 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')]} 05:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 05:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/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 05:00:35 INFO - Using env: {'DISPLAY': ':0', 05:00:35 INFO - 'HOME': '/home/cltbld', 05:00:35 INFO - 'LANG': 'en_US.UTF-8', 05:00:35 INFO - 'LANGUAGE': 'en_US:en', 05:00:35 INFO - 'LOGNAME': 'cltbld', 05:00:35 INFO - 'MAIL': '/var/mail/cltbld', 05:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:35 INFO - 'MOZ_NO_REMOTE': '1', 05:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:35 INFO - 'NO_EM_RESTART': '1', 05:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:35 INFO - 'PWD': '/builds/slave/test', 05:00:35 INFO - 'SHELL': '/bin/bash', 05:00:35 INFO - 'SHLVL': '1', 05:00:35 INFO - 'TERM': 'linux', 05:00:35 INFO - 'TMOUT': '86400', 05:00:35 INFO - 'USER': 'cltbld', 05:00:35 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:35 INFO - '_': '/tools/buildbot/bin/python'} 05:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:35 INFO - Running setup.py (path:/tmp/pip-sKdVL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:35 INFO - Running setup.py (path:/tmp/pip-ViTB07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:36 INFO - Running setup.py (path:/tmp/pip-yiYj6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:36 INFO - Running setup.py (path:/tmp/pip-snBuX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:36 INFO - Running setup.py (path:/tmp/pip-5JnJGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:36 INFO - Running setup.py (path:/tmp/pip-6B3ZcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:36 INFO - Running setup.py (path:/tmp/pip-hslUOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:36 INFO - Running setup.py (path:/tmp/pip-yOaTtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:36 INFO - Running setup.py (path:/tmp/pip-WrLkMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:36 INFO - Running setup.py (path:/tmp/pip-P3u4K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:36 INFO - Running setup.py (path:/tmp/pip-512wPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:36 INFO - Running setup.py (path:/tmp/pip-zxhLme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:36 INFO - Running setup.py (path:/tmp/pip-QxBoBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:36 INFO - Running setup.py (path:/tmp/pip-h2yU6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:36 INFO - Running setup.py (path:/tmp/pip-wAyVcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:36 INFO - Running setup.py (path:/tmp/pip-irsqxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:37 INFO - Running setup.py (path:/tmp/pip-dUo3BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:37 INFO - Running setup.py install for manifestparser 05:00:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Running setup.py install for mozcrash 05:00:37 INFO - Running setup.py install for mozdebug 05:00:37 INFO - Running setup.py install for mozdevice 05:00:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Running setup.py install for mozfile 05:00:37 INFO - Running setup.py install for mozhttpd 05:00:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Running setup.py install for mozinfo 05:00:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Running setup.py install for mozInstall 05:00:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:37 INFO - Running setup.py install for mozleak 05:00:37 INFO - Running setup.py install for mozlog 05:00:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Running setup.py install for moznetwork 05:00:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Running setup.py install for mozprocess 05:00:38 INFO - Running setup.py install for mozprofile 05:00:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Running setup.py install for mozrunner 05:00:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Running setup.py install for mozscreenshot 05:00:38 INFO - Running setup.py install for moztest 05:00:38 INFO - Running setup.py install for mozversion 05:00:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:38 INFO - Cleaning up... 05:00:38 INFO - Return code: 0 05:00:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:38 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')]} 05:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:38 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')]} 05:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 05:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/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 05:00:38 INFO - Using env: {'DISPLAY': ':0', 05:00:38 INFO - 'HOME': '/home/cltbld', 05:00:38 INFO - 'LANG': 'en_US.UTF-8', 05:00:38 INFO - 'LANGUAGE': 'en_US:en', 05:00:38 INFO - 'LOGNAME': 'cltbld', 05:00:38 INFO - 'MAIL': '/var/mail/cltbld', 05:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:38 INFO - 'MOZ_NO_REMOTE': '1', 05:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:38 INFO - 'NO_EM_RESTART': '1', 05:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:38 INFO - 'PWD': '/builds/slave/test', 05:00:38 INFO - 'SHELL': '/bin/bash', 05:00:38 INFO - 'SHLVL': '1', 05:00:38 INFO - 'TERM': 'linux', 05:00:38 INFO - 'TMOUT': '86400', 05:00:38 INFO - 'USER': 'cltbld', 05:00:38 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:38 INFO - '_': '/tools/buildbot/bin/python'} 05:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:39 INFO - Running setup.py (path:/tmp/pip-aOHBXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:39 INFO - Running setup.py (path:/tmp/pip-xGZhED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:39 INFO - Running setup.py (path:/tmp/pip-jO5S_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:39 INFO - Running setup.py (path:/tmp/pip-6zxE9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:39 INFO - Running setup.py (path:/tmp/pip-K_Wcgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:39 INFO - Running setup.py (path:/tmp/pip-fnv81M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:39 INFO - Running setup.py (path:/tmp/pip-16mLkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:39 INFO - Running setup.py (path:/tmp/pip-39CZvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:39 INFO - Running setup.py (path:/tmp/pip-523yt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:39 INFO - Running setup.py (path:/tmp/pip-tMTdfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:39 INFO - Running setup.py (path:/tmp/pip-ehhcqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:39 INFO - Running setup.py (path:/tmp/pip-9BqrnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:39 INFO - Running setup.py (path:/tmp/pip-oCkbf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:40 INFO - Running setup.py (path:/tmp/pip-KRzcsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:40 INFO - Running setup.py (path:/tmp/pip-D6OyGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:40 INFO - Running setup.py (path:/tmp/pip-F3Q5Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:40 INFO - Running setup.py (path:/tmp/pip-15Qp9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:40 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 05:00:40 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 05:00:40 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 05:00:40 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 05:00:40 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 05:00:40 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 05:00:41 INFO - Downloading blessings-1.5.1.tar.gz 05:00:41 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:42 INFO - Installing collected packages: blessings 05:00:42 INFO - Running setup.py install for blessings 05:00:42 INFO - Successfully installed blessings 05:00:42 INFO - Cleaning up... 05:00:42 INFO - Return code: 0 05:00:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:42 INFO - Reading from file tmpfile_stdout 05:00:42 INFO - Current package versions: 05:00:42 INFO - argparse == 1.2.1 05:00:42 INFO - blessings == 1.5.1 05:00:42 INFO - blobuploader == 1.2.4 05:00:42 INFO - docopt == 0.6.1 05:00:42 INFO - manifestparser == 1.1 05:00:42 INFO - mozInstall == 1.12 05:00:42 INFO - mozcrash == 0.16 05:00:42 INFO - mozdebug == 0.1 05:00:42 INFO - mozdevice == 0.46 05:00:42 INFO - mozfile == 1.2 05:00:42 INFO - mozhttpd == 0.7 05:00:42 INFO - mozinfo == 0.8 05:00:42 INFO - mozleak == 0.1 05:00:42 INFO - mozlog == 3.0 05:00:42 INFO - moznetwork == 0.27 05:00:42 INFO - mozprocess == 0.22 05:00:42 INFO - mozprofile == 0.27 05:00:42 INFO - mozrunner == 6.11 05:00:42 INFO - mozscreenshot == 0.1 05:00:42 INFO - mozsystemmonitor == 0.0 05:00:42 INFO - moztest == 0.7 05:00:42 INFO - mozversion == 1.4 05:00:42 INFO - psutil == 3.1.1 05:00:42 INFO - requests == 1.2.3 05:00:42 INFO - wsgiref == 0.1.2 05:00:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f2023b9dc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e77b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24b9ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 05:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 05:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/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 05:00:42 INFO - Using env: {'DISPLAY': ':0', 05:00:42 INFO - 'HOME': '/home/cltbld', 05:00:42 INFO - 'LANG': 'en_US.UTF-8', 05:00:42 INFO - 'LANGUAGE': 'en_US:en', 05:00:42 INFO - 'LOGNAME': 'cltbld', 05:00:42 INFO - 'MAIL': '/var/mail/cltbld', 05:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:42 INFO - 'MOZ_NO_REMOTE': '1', 05:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:42 INFO - 'NO_EM_RESTART': '1', 05:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:42 INFO - 'PWD': '/builds/slave/test', 05:00:42 INFO - 'SHELL': '/bin/bash', 05:00:42 INFO - 'SHLVL': '1', 05:00:42 INFO - 'TERM': 'linux', 05:00:42 INFO - 'TMOUT': '86400', 05:00:42 INFO - 'USER': 'cltbld', 05:00:42 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:42 INFO - '_': '/tools/buildbot/bin/python'} 05:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:42 INFO - Cleaning up... 05:00:42 INFO - Return code: 0 05:00:42 INFO - Running post-action listener: _resource_record_post_action 05:00:42 INFO - Running post-action listener: _start_resource_monitoring 05:00:42 INFO - Starting resource monitoring. 05:00:42 INFO - ##### 05:00:42 INFO - ##### Running install step. 05:00:42 INFO - ##### 05:00:42 INFO - Running pre-action listener: _resource_record_pre_action 05:00:42 INFO - Running main action method: install 05:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:42 INFO - Reading from file tmpfile_stdout 05:00:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:42 INFO - Reading from file tmpfile_stdout 05:00:42 INFO - Output received: 05:00:42 INFO - Usage: mozinstall [options] installer 05:00:42 INFO - Options: 05:00:42 INFO - -h, --help show this help message and exit 05:00:42 INFO - -d DEST, --destination=DEST 05:00:42 INFO - Directory to install application into. [default: 05:00:42 INFO - "/builds/slave/test"] 05:00:42 INFO - --app=APP Application being installed. [default: firefox] 05:00:42 INFO - mkdir: /builds/slave/test/build/application 05:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:00:56 INFO - Reading from file tmpfile_stdout 05:00:56 INFO - Output received: 05:00:56 INFO - /builds/slave/test/build/application/firefox/firefox 05:00:56 INFO - Running post-action listener: _resource_record_post_action 05:00:56 INFO - ##### 05:00:56 INFO - ##### Running run-tests step. 05:00:56 INFO - ##### 05:00:56 INFO - Running pre-action listener: _resource_record_pre_action 05:00:56 INFO - Running main action method: run_tests 05:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:00:56 INFO - Python 2.7.3 05:00:56 INFO - Return code: 0 05:00:56 INFO - grabbing minidump binary from tooltool 05:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:56 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/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 0x2548250>, '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 0x24b80b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24b8540>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:00:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:00:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:00:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:00:57 INFO - Return code: 0 05:00:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:00:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:00:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:00:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:00:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost'] in /builds/slave/test/build 05:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost 05:00:57 INFO - Using env: {'DISPLAY': ':0', 05:00:57 INFO - 'HOME': '/home/cltbld', 05:00:57 INFO - 'LANG': 'en_US.UTF-8', 05:00:57 INFO - 'LANGUAGE': 'en_US:en', 05:00:57 INFO - 'LOGNAME': 'cltbld', 05:00:57 INFO - 'MAIL': '/var/mail/cltbld', 05:00:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:00:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:57 INFO - 'MOZ_NO_REMOTE': '1', 05:00:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:00:57 INFO - 'NO_EM_RESTART': '1', 05:00:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:57 INFO - 'PWD': '/builds/slave/test', 05:00:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:00:57 INFO - 'SHELL': '/bin/bash', 05:00:57 INFO - 'SHLVL': '1', 05:00:57 INFO - 'TERM': 'linux', 05:00:57 INFO - 'TMOUT': '86400', 05:00:57 INFO - 'USER': 'cltbld', 05:00:57 INFO - 'XDG_SESSION_COOKIE': 'b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477', 05:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:57 INFO - '_': '/tools/buildbot/bin/python'} 05:00:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost'] with output_timeout 3600 05:00:57 INFO - mozversion INFO | application_buildid: 20151024034732 05:00:57 INFO - mozversion INFO | application_changeset: 05d85d9bbce4660987a69574cc40b19157e42fea 05:00:57 INFO - mozversion INFO | application_display_name: Nightly 05:00:57 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:00:57 INFO - mozversion INFO | application_name: Firefox 05:00:57 INFO - mozversion INFO | application_remotingname: firefox 05:00:57 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:00:57 INFO - mozversion INFO | application_vendor: Mozilla 05:00:57 INFO - mozversion INFO | application_version: 44.0a1 05:00:57 INFO - mozversion INFO | platform_buildid: 20151024034732 05:00:57 INFO - mozversion INFO | platform_changeset: 05d85d9bbce4660987a69574cc40b19157e42fea 05:00:57 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:00:57 INFO - mozversion INFO | platform_version: 44.0a1 05:00:57 INFO - 2015-10-24 05:00:57,666 DEBUG : using testdate: 1445688057 05:00:57 INFO - 2015-10-24 05:00:57,666 DEBUG : actual date: 1445688057 05:00:57 INFO - 2015-10-24 05:00:57,679 INFO : starting webserver on 'localhost:45606' 05:00:57 INFO - 2015-10-24 05:00:57,680 INFO : Starting test suite talos-linux64-ix-001 05:00:57 INFO - 2015-10-24 05:00:57,680 INFO : Starting test dromaeo_css 05:00:57 INFO - 2015-10-24 05:00:57,680 DEBUG : operating with platform_type : linux_ 05:00:57 INFO - 2015-10-24 05:00:57,681 INFO : Initialising browser for dromaeo_css test... 05:00:57 INFO - 2015-10-24 05:00:57,727 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZpoxSf/profile http://localhost:45606/getInfo.html 05:01:02 INFO - 2015-10-24 05:01:02,795 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:01:02 INFO - 2015-10-24 05:01:02,795 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:01:02 INFO - 2015-10-24 05:01:02,795 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:01:02 INFO - 2015-10-24 05:01:02,796 DEBUG : BROWSER_OUTPUT: __metrics 05:01:02 INFO - 2015-10-24 05:01:02,944 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:01:04 INFO - 2015-10-24 05:01:04,097 INFO : Browser initialized. 05:01:04 INFO - 2015-10-24 05:01:04,098 INFO : Running cycle 1/1 for dromaeo_css test... 05:01:04 INFO - 2015-10-24 05:01:04,098 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZpoxSf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:01:16 INFO - 2015-10-24 05:01:16,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 141385728 05:01:16 INFO - 2015-10-24 05:01:16,049 DEBUG : BROWSER_OUTPUT: 05:02:06 INFO - 2015-10-24 05:02:06,897 DEBUG : BROWSER_OUTPUT: 1445688126896 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] 05:04:20 INFO - 2015-10-24 05:04:20,343 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45606/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45606/tests/dromaeo/cssquery-ext.html) 05:04:21 INFO - 2015-10-24 05:04:21,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 256958464 05:04:21 INFO - 2015-10-24 05:04:21,428 DEBUG : BROWSER_OUTPUT: 05:07:23 INFO - 2015-10-24 05:07:23,948 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45606/tests/dromaeo/cssquery-ext.html (next: http://localhost:45606/tests/dromaeo/cssquery-jquery.html) 05:07:24 INFO - 2015-10-24 05:07:24,521 DEBUG : BROWSER_OUTPUT: RSS: Main: 222244864 05:07:24 INFO - 2015-10-24 05:07:24,521 DEBUG : BROWSER_OUTPUT: 05:10:26 INFO - 2015-10-24 05:10:26,761 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45606/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45606/tests/dromaeo/cssquery-mootools.html) 05:10:27 INFO - 2015-10-24 05:10:27,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 203501568 05:10:27 INFO - 2015-10-24 05:10:27,331 DEBUG : BROWSER_OUTPUT: 05:13:29 INFO - 2015-10-24 05:13:29,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45606/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45606/tests/dromaeo/cssquery-prototype.html) 05:13:29 INFO - 2015-10-24 05:13:29,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 200773632 05:13:29 INFO - 2015-10-24 05:13:29,836 DEBUG : BROWSER_OUTPUT: 05:16:32 INFO - 2015-10-24 05:16:32,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45606/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45606/tests/dromaeo/cssquery-yui.html) 05:16:33 INFO - 2015-10-24 05:16:33,170 DEBUG : BROWSER_OUTPUT: RSS: Main: 202805248 05:16:33 INFO - 2015-10-24 05:16:33,171 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,636 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45606/tests/dromaeo/cssquery-yui.html (next: http://localhost:45606/tests/dromaeo/cssquery-dojo.html) 05:19:35 INFO - 2015-10-24 05:19:35,895 DEBUG : BROWSER_OUTPUT: RSS: Main: 241594368 05:19:35 INFO - 2015-10-24 05:19:35,895 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,895 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:19:35 INFO - 2015-10-24 05:19:35,895 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:19:35 INFO - 2015-10-24 05:19:35,896 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:19:35 INFO - 2015-10-24 05:19:35,896 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:19:35 INFO - 2015-10-24 05:19:35,896 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2622.986885065575;2691.1657386210263;2694.5688689809635;2718.2253057878497;2724.359775452768;4620.676552641308;4720.740359280238;4795.784189711462;4826.855194344175;4834.419869615646;4726;4758.262469317261;4797.280407938802;4802.927956080662;4804.83183088592;4390.934135987963;4411.1177764447075;4435.46774387074;4464.508904020555;4494.5730155635165;4226.091390351058;4452.666050046243;4456.621187199092;4469.553044695537;4513.909721805562;4359.956400435986;4367.410399596046;4390.780460976938;4451.354553589744;4479.977600111979;4414.05097903949;4446.510883802779;4448.377227188196;4477.306017567283;4492.66305027121;4416.447944006999;4437.334399840018;4443.577860103285;4450.4881938577155;4490.640748740126;4502.549745025472;4605.930911036304;4606.378138951232;4639.76801159944;4736.573708366263;5090.949090509122;5095.057414378315;5101.540861322499;5103.897922041576;5129.563987061104;5108.361454818148;5113.054084994288;5113.616478764107;5116.232565115262;5159.793608255665;4536.795844186953;4601.24079526881;4635.7450340230935;4648.930266046012;4741.905161896777;5199.376074871039;5203.583713303003;5221.712805795718;5231.738413079331;5271.051210782096;5031.320771695848;5039.042581909425;5039.193729003342;5046.596272298208;5078.035173317058;4876.292937524039;5016.347874776256;5039.672421292605;5056.191009438546;5123.513266239701;5037.823676171316;5047.798088076436;5048.6718363307;5049.899002020013;5059.87350316245;4995.050940321327;5004.324416203839;5006.824761133415;5014.598832093424;5033.421156566968;4102.261592913304;4188;4197.076643138505;4203.453551038346;4296.0978194578865;3713.702903767692;3722.925541489155;3734.2718169957143;3744.4008958566496;3774.641409066189;4352.2818734909015;4357.4988876279;4359.106383191438;4380.956190438119;4405.471343438744;4816.903661926742;4922.680025798311;4923.433805112386;4949.876253093701;5087.8982420351385;4329.6969212154845;4344.739315641072;4345.239583072949;4350.695451318441;4376.846810361686;4540.182767101887;4546.590806827401;4547.3406356078185;4561.635069194456;4582.587567118975;4588.793504292382;4707.129181101507;4707.317438971399;4708.976455117702;4819.108464933998;5073.96206485946;5074;5077.517635824591;5080.339555857862;5097.0315640028275;4819.180739274261;4824.807007719793;4826.227803551415;4828.227483602747;4846.975765121152;4786.497417771223;4895.632827537877;4898.436679781871;4904.092742842586;5071.568916642127;5063.721495317793;5086.160783470686;5098.439171691037;5105.591552675852;5116.28372027909;4915.680480768881;4922.532359425849;4928.654994150375;4932.8520144395725;4964.478729733326;4668.626509879269;4765;4781.784819683053;4789.114013907439;4956.10790057793;4833.516648335279;4837.05677392905;4840.419149702058;4840.927386089141;4861.6596838220985;4950.257461380822;4975.875603109951;4977.950220497749;4982.4768399317545;5018.4981501849525;4638.814447422065;4728.148933192058;4728.172569800203;4740.288956656529;4910.975445122895 05:19:35 INFO - 2015-10-24 05:19:35,896 DEBUG : BROWSER_OUTPUT: |1;ext.html;498.1755195152023;500.35204410288674;501.80429632443355;530.989380212396;544.8419958212116;28838.711612883864;38213.61786382135;38799.612003879985;38854.41718374227;38895;40871.38692919609;40960;41397.17205655885;41558;41570.16859662812;7049.7885063448175;7195.388391986688;8065.516069035847;8208.630611622462;8316.002079750438;10385.221108416861;10690.679279621585;10693;10693.572257109707;10700;10228.386296822215;10343.22425818063;10346;10361.430121343323;10368.118709909666;7186.101737282814;7409.147947985996;9978.101970822661;10151.289409741321;10194.64318748844;9722.125008749246;9836.213102951819;9838.65564705239;9841.311108222428;9848.310618256724;36159;36820.0794980125;36841;36847.4472882907;36856.81571592152;2829.2785339738234;2881.5821705852745;2896.145637037058;3393;3551.1122219445137;28164.15507534777;28248.717512824813;28521.71478285232;28584.714152858414;28816.27959301013;2587.573050446674;2599.2851965709583;2602.648642433266;2631.368471566829;2770.5290100682932;240105;241104;242156.78921605455;242252;243038;53821;55638.16542751781;60204;60903.69548152231;62856;61740.69129654323;61816.69091654513;61877;62042;62061;14722.337494812758;14956.551303460914;14992;14999.17504537239;15031.774523382159;11152.163587730953;11196.832047519294;11258.324500529994;11289.0968722502;11289.435528223556;19185.06955146988;19608.21567137327;19612.215511379385;19624;19640.901795490932;7885.1326354101;8259.339252859756;8323.500589964622;8362.8327433451;8446.197611226924;799.7200979657074;857.8798968144465;917.2071043672034;943.5895385507189;975.8048390321964;27100.1869943902;27137.1858844235;27170.5924411134;27328.45343093127;27587.448251034868;937.4843835890233;939.7462685074992;966.3766870368573;986.6201512417771;1070.8607880975578;13561.660958476117;13959.720805583831;14100.435982560582;14205.431782728576;14219;320261;325307;325532;327590;327624;49502.50497495051;50075;50075.24887126696;50127.74936125296;50140;1185.5080141741253;1198.1193822540426;1198.328935795957;1218.5369559567307;1221.3770976802073;26049.218523444328;26579.734202657724;26844.328891777863;26861.597076043483;27092.45815083727;37308;37871.43192852165;39717.2056558873;42818.5718142827;43693.78153109214;240906;240931;243186;243558;243630;24877.378065547782;30231.395372092877;30760.23099422443;30793.69206307908;30798.076057718303;29622.259443514085;34955.650443496255;35884.28231435305;36362;36826.63173368339;34467.655323446445;34670.30659386748;35019.824900875334;35304.823475882455;35455.290894182486;33864.83067584646;34092.14769630779;34139;39027.41458878176;40856.38715419212;35043.474347884294;35613;35722.10694732548;35772.28455430929;35879;28649.28376790597;31073.533896992016;31104.844475777478;31243;31634.367312653158;24714.50570988606;24720.50558988846;24770.50458990774;25032;25066.37334066663 05:19:35 INFO - 2015-10-24 05:19:35,896 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.2515579860192;360.8069682719745;362.1001810500905;363.6218332933749;364.12064863355;12815.295158766265;13134.277614731185;15261.542153735392;16281.185940702962;17472.12639368033;15575.376984920618;15690.607734806606;15715.921420392882;15908.681826363465;15908.76136857948;4587.610053145487;4686.718796872182;4690;4694.17851875922;5062.557171206207;3190.489521676532;3271.96728032719;4650;4694.624430045606;4806.519348065183;4715.174844402216;4751.667383283171;4754.714717116966;4755.571998520132;4774.594159496447;4725.054989002196;4799.040191961604;4811.735354555498;4831.227003679394;4832.057748738997;4142.482189726285;4150.294449943517;4154.771487568182;4157.064660451404;4159.750414975111;12452.190607610477;12588.748225035539;13160.07879448439;13189.934050329783;13190;1535.831058583555;1589.4595837415218;1651.2981982657323;1744.8080711121765;1746.589551455407;9082.357135076834;9317.39436936597;9324.106663485067;9407.717768466957;9486.430814151174;1580.1546172797614;1596.2657177698272;1603.9037657740457;1613.5482065021813;1617.4096454794033;52317.47682523164;52685.7365713169;53408;53598.46401536013;54460.183097253575;6250.343713909974;6338.492920566344;6476.384743449365;6564.113844631009;6618.338166183439;7358.77923662291;7362.263773622595;7362.963185184147;7363.631818409165;7368.778936631909;5334.59990500714;5343.679379237258;5360.597955153379;5365.195220716923;5376.1129413647095;5512.118061110203;5536.557075434036;5539.14143307788;5542.085555883315;5556.305461817273;3049.451098802237;3069.202007478027;3099.3256281206964;3100.4264211120562;3116.6727493612843;1058.6612284069179;1095.7534554725123;1110.5446766825562;1149.0520320735427;1168.0888906652824;384.58464857953345;403.40296361385316;413.6669980665593;417.9540250572435;429.8796337025638;10100.494975251208;10295.74260643475;10500.054995050483;10762.09476309227;10865.348079115278;395.116913697885;400.5393797133262;428.5286185196321;442.52428639212974;443.0363958390488;5535.086710692771;5844.327902291206;6112.419320164578;6329.525285603506;6338.651374174465;478993;481645;488216;488681;495700;57451.42548574544;65763.34236657667;68875;70255.29744702589;71072.2892771076;487.4029314090282;493.5163539731084;498.5911552526893;499.1065991874504;499.29599265036126;10062.586199170531;10554.947225264132;10891.509882055168;10962.177836662122;11156.888431115909;31944.68055319417;32161.35677286488;32884;33546.496802547495;33730.62693730607;39791.204175915744;39793.60206397899;39873;40934.1813163741;41273.587264128175;7270.781876543712;7512.774616761506;7526.698932042876;7689.115751688628;7926.960365198368;11617.709557261136;11672.76654466889;11798.941005294919;11802.586909458129;11827.290362578273;10085.647002354883;10184.643537476153;10292.330998485075;10314.638987635397;10448.216383771096;9761.219102471643;10098.545565449425;10155.441450720282;10168.542415591168;10351.741206469596;9786;10344.948275258575;10371.222158338305;10617.150627949592;10636.680899573024;6768.424683901727;6776.695048722918;7788.8831667526165;7842.921570784448;7850.018747656543;10223.59105635796;10289.382637041801;10345.224108191765;10363.170946324426;10367.792644147226 05:19:35 INFO - 2015-10-24 05:19:35,898 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1327.2633688303;1443.7040406716621;1606.3012589523557;1634.256413331934;1789.8926064436143;7411.110666719995;8502.192291732283;8542;8562.058173600899;8616.052234254226;8535.27450166735;8862.64549418024;8870.334724895627;8870.334724895627;8880.777980550489;2107.1571371451428;2132.9893350533225;2154.5583155453096;2156.611809874222;2156.8274538037;2182.8799416032193;2200.7469141048823;2244.046280330861;2259.898304576298;2261.7512073671883;2275.681404603357;2277.9658305125436;2295.7130358705163;2311.659651803145;2316.6409206573007;2215.268961242786;2240.3838944290287;2246.292417888368;2252.538229662915;2255.8759268240324;2246.4608493961496;2256.4133325335365;2265.648824432216;2265.8187345012525;2268.6029944759766;7322.48742588019;8408.411411201158;8444.57777111142;8501.659933602648;8503.23470887623;4261.275583150872;4726.385569875929;4854.538818812207;4858.684185527929;4867.4889136640495;2820.9153725388273;2859.642544681915;2965.9110226693233;3004.173852190643;3014.14096982359;829.7718127514921;867.3234876796046;873.244643383475;915.5580603220284;924.0943875002536;13034.739305213843;13089.149205301625;13143.671408214776;13163.802542961863;13170.67073323165;4563.680542362003;4666.32338310943;4684.74233917131;4740.336352910595;4742.33607294979;10871.83692244617;10917.344959302467;10923.126149907988;10928.508217130247;10930.125589952784;4865.683730557503;4869.586085182799;4878.82924097662;4881.780319885614;4885.169521181408;4353.3469979503325;4373.037931655031;4379.3430985351815;4390.100029493946;4397.714148580332;3752.0057184846037;3808.295465338921;3833.8083095845095;3864.5942176071107;3875.922481550353;1206.8129439936824;1207.6860016395624;1210.7215340471564;1214.6963259185204;1224.6326102169314;840.2437805974672;851.8381507513552;853.7865533616596;854.7136709202363;1146.7649131927935;8284.08875023747;8285.047219569826;8319.66721331152;8352.665893364197;8393.328533717287;470.3438703009283;472.69274971268266;1128.5090985421368;1179.2511755035443;1202.8015377462796;5893.410658934158;6231.2522497300615;6268.717907694165;6296.748130074838;6301.14934483848;9947.353422027545;11173.385463799568;11225.270357426742;11242.550297988153;11251.381174035343;10893.673189804318;11012.889871101186;11061.944690276498;11173.217874748852;11301.773964520664;8142.430029897969;8241.79395515105;8245.464044837066;8248.54632995179;8249.463784854086;8257.587120644064;8275.462094963808;8282.58587070632;8340.082590914873;8367.748967530984;9594.856077158709;9660.661876834558;9667.661631842851;9681.854772178282;9750.317477776487;9699.321047526606;10042.899571004196;10043.347182433119;10063.849042264226;10076.345037572535;7031.332023457763;7195.02867112933;7197.1363436387965;7206.2793720627515;7241.094863142107;8578.828423431622;8615.138486151334;8643.654253829776;8651.351148663749;8672.089430609694;7817.257360550739;7861.331786798186;7899.644515996909;8038.356931445587;8086.959565202136;7710.190430004769;7713;7783.260590243918;7822.687092516236;8054.114047454658;7618.162002179645;7809.648565814666;7857.410694198073;7909.49719553283;7914.643841027052;7172.426205903619;7194.6042967635185;7205;7206.171290301682;7233.891491627524;7049.577025378494;7169.928300716926;7302.671379788028;7392.149902761036;7425 05:19:35 INFO - 2015-10-24 05:19:35,899 DEBUG : BROWSER_OUTPUT: |4;prototype.html;328.3154622611854;330.6941079501461;334.9129226401135;341.7966310045522;343.2517112694327;5177.534021938025;5194.091034069037;5195.142801437768;5195.532402083812;5206.349206349207;4943.802247910088;5018.49815018498;5022.347094877661;5111.846644600668;5114.514121158493;4033.8588149414777;4044.8179831907637;4048.4332193492933;4052.2705912935735;4060.4518390017265;3872.2255548890193;4048.089179934506;4049.574794646564;4049.6355328020472;4049.7367671101433;3889.0666240102455;3984.5417776955587;4056.736312139716;4057.452243947074;4059.8173082211224;4024.9798751006206;4025.3961905714086;4034.0519977805193;4035.5762644922156;4040.373742069984;3627.5828279747902;3629.401148810443;3629.7277704172293;3629.981850090759;3629.981850090759;4907.803687852482;4939.777710003059;4940.53064958832;4945;4946.975265123651;4536.682432229745;4575.245084561044;4632.490426053165;4633.443986721615;4634.675572709912;5364.14173732201;5371.892562148735;5373.328333958255;5373.919391209135;5376.78492860289;3896.7272290939645;3897.4153876918404;3897.7661340319673;3904.7071469639604;3905.5313362396696;5623.718814059281;5626.240457538221;5637.041702910515;5638.097904335327;5642.548596112342;3933.218465925968;4016.136530645925;4041.8989525262105;4043.251998380309;4053.8378464861075;4089.2639324921206;4092.9386059209137;4093.4473846030405;4095.140020595649;4097.364908439196;4600.217962946307;4607.401037865123;4608.446986361658;4609.354690343354;4616;5342.893142137135;5350.732463376893;5353.357597088374;5353.57171426285;5354.223637572531;1447.9638009049859;1464.7656374979952;1482.0588926031833;1482.6145202247278;1483.1842486632308;1030.9845352319721;1039.0596510158318;1040.3445829127602;1041.3855825063097;1041.8020576090519;4730;4733.384659994179;4736.715797052188;4738.099761045391;4738.384010078667;5525.254090697785;5527.778888844482;5533.944660553423;5535.750891209906;5536.557075433956;4567.931481027787;4620.491745907947;4635.58279754817;4726.834560790356;4731.574158325767;2989.701029896993;3011.5633233181065;3022.788404811686;3024.2741741981768;3027.7880548361404;5210.97394513025;5212.713300768494;5213.452587478336;5214.270002199695;5219.086659834514;5627.155926610959;5627.915581266202;5628.662280263197;5629.549636029108;5634.04221282383;5111.463296353903;5180.637355385087;5211.973940130351;5214.478552144755;5215.24378965048;5484.972575137099;5485.588580856532;5491.203775452617;5498;5500;5348.224507446476;5349.411564727799;5349.839504814861;5351.973240133774;5354.089804733283;5110.693358398508;5194.532492075731;5197.7141257229705;5206.0368831766245;5209.81765638216;3870.3613903705573;3912.315344814589;3920.431537427;3920.725549211642;3921.8039098044414;5457.208704737712;5467.535259502987;5481.095619222783;5483.985462689256;5485.9725701371235;5305.363356397257;5309.973450132725;5313.282706834528;5314.97342513285;5315.282436870973;5175.353080864892;5299.549538289289;5300.840974770763;5312.920306195333;5323.467653234646;5259.63182577216;5298.7350632469;5300;5300.045991721528;5300.496452836975;5369.919451208313;5370.704611246419;5371.704556249444;5373.140297552372;5407.891842163056;5239.973800130975;5241.9737901310245;5245.763940622604;5247.842564723064;5248.343957005374 05:19:35 INFO - 2015-10-24 05:19:35,900 DEBUG : BROWSER_OUTPUT: |5;yui.html;601.380578004655;688.9655517224144;756.9053868266467;761.2919984414496;790.6560646118938;10385.117265032472;10563.894361056387;10572.947135264323;10592.78814423711;10596.152307815357;10084.294099413044;11784.410779461035;11971.281723096621;11985.101117416207;11990.40047997601;622.9003359462487;626.3767551286477;777.215012837033;790.6639678136797;791.1653205867807;758.5069704691939;787.7400457848896;790.1387487638473;790.1663744749287;791.3550456378063;815.3029160068145;825.5253229393093;828.6850996621275;829.8091438969041;831.7296878514477;640.8205702403335;651.0494677770461;797.9002624671916;824.7731873734741;825.2160447574798;596.0433504225699;596.7374355283662;597.0983814440183;597.9342272350082;598.9760409583612;10981.066609338215;11024.889751102546;11103.278286911325;11119.499622517005;11127.888721112848;7826.804329891798;7996.360291176692;8085.595720214024;8147.348212143014;8171.10117887032;7644;7762.844743105106;7834.764957051298;7908.327792137675;7950.443468957175;2473.2703852363416;2497.313238859328;2502.799776017914;2528.05198050731;2548.3374322676236;31999.360012799847;32251.999999999764;32265.35469290601;32289;32313.1921701957;5831.504322132666;5970.432808883149;5978.5814992950945;6129.540284478682;6194.0708893666315;9496.810063798823;9519.476428796483;9519.666811661558;9528.714138575853;9532.428054316762;5082.669626474268;5153.3558305211845;5171.793128274902;5212.478752124757;5380.892382152335;3132.4831402818736;3146.166265939528;3149.7952633079235;3157.2738270197983;3267.18320419895;2379.5835728747747;2396.868172250543;2410.650455683916;2412.300432874447;2414.649875768039;392.5289652417111;393.1959143551444;399.2773080723899;403.39692160220073;403.50369046073496;395.9180845483079;423.4918098282074;436.3433033284886;457.22728588685015;460.15102136558;6550.7707230246715;6619.966900165565;6728.42808361285;6894.655267236618;6982.197047339621;469.76746510477534;489.4493694593582;528.9289189391436;581.1718301420459;592.7391947543067;3207.5028370602595;3305.685959833812;3361.83190840457;3378.4256676365076;3386.9661303387143;42200.57799421967;42448.57551424446;42484;42517;42533.36199957003;35198.64801352005;35775.4633680495;35813.64186358155;35860.82069589687;35921;30192.84903575468;30399.240018999702;30609.540856887164;31064.378712425623;31105.37789244157;6546;6572.112764776885;6613.437857782143;6688.264290928091;6704.161979752531;22502.21242256513;22893.198738044088;23524.29427117121;23659.64510532309;24038.278851634477;30330;30437;30542.541861872534;30940.22649433782;31307.373852523277;6652;6696.497762667722;6752.324767523209;6913.446924245947;7137.250588688113;9883.30816842814;10150.340227885164;10283.228757843042;10390.324628899098;10462.47687615601;9642.276829237695;9709.6116155353;9851.852222216816;9855.11303982645;10013.599456021677;9236.815263694823;9370.953145234504;9447.905520944978;9516.286278529;9878.308518403643;9249.30630202724;9865.802683946424;10052.597896084075;10098.798024039625;10101.44442055694;8119.5534245617055;8235.876461852957;8247.463914845515;8265.917340826514;8274.834503310021;7655.961720191363;7927.246911543394;8156.306713929383;8163.40723152502;8199.262066414052 05:19:35 INFO - 2015-10-24 05:19:35,900 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:19:35 INFO - 2015-10-24 05:19:35,900 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:19:35 INFO - 2015-10-24 05:19:35,900 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,846 05:19:35 INFO - 2015-10-24 05:19:35,900 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:19:35 INFO - 2015-10-24 05:19:35,900 DEBUG : BROWSER_OUTPUT: __startTimestamp1445689175889__endTimestamp 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4675.97 Median:4795.78 stddev:479.86 (10.0%) stddev-sans-first:453.50 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: Values: 2623.0 2691.2 2694.6 2718.2 2724.4 4620.7 4720.7 4795.8 4826.9 4834.4 4726.0 4758.3 4797.3 4802.9 4804.8 4390.9 4411.1 4435.5 4464.5 4494.6 4226.1 4452.7 4456.6 4469.6 4513.9 4360.0 4367.4 4390.8 4451.4 4480.0 4414.1 4446.5 4448.4 4477.3 4492.7 4416.4 4437.3 4443.6 4450.5 4490.6 4502.5 4605.9 4606.4 4639.8 4736.6 5090.9 5095.1 5101.5 5103.9 5129.6 5108.4 5113.1 5113.6 5116.2 5159.8 4536.8 4601.2 4635.7 4648.9 4741.9 5199.4 5203.6 5221.7 5231.7 5271.1 5031.3 5039.0 5039.2 5046.6 5078.0 4876.3 5016.3 5039.7 5056.2 5123.5 5037.8 5047.8 5048.7 5049.9 5059.9 4995.1 5004.3 5006.8 5014.6 5033.4 4102.3 4188.0 4197.1 4203.5 4296.1 3713.7 3722.9 3734.3 3744.4 3774.6 4352.3 4357.5 4359.1 4381.0 4405.5 4816.9 4922.7 4923.4 4949.9 5087.9 4329.7 4344.7 4345.2 4350.7 4376.8 4540.2 4546.6 4547.3 4561.6 4582.6 4588.8 4707.1 4707.3 4709.0 4819.1 5074.0 5074.0 5077.5 5080.3 5097.0 4819.2 4824.8 4826.2 4828.2 4847.0 4786.5 4895.6 4898.4 4904.1 5071.6 5063.7 5086.2 5098.4 5105.6 5116.3 4915.7 4922.5 4928.7 4932.9 4964.5 4668.6 4765.0 4781.8 4789.1 4956.1 4833.5 4837.1 4840.4 4840.9 4861.7 4950.3 4975.9 4978.0 4982.5 5018.5 4638.8 4728.1 4728.2 4740.3 4911.0 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,901 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43585.31 Median:326561.00 stddev:71237.82 (21.8%) stddev-sans-first:71364.20 05:19:35 INFO - 2015-10-24 05:19:35,902 DEBUG : BROWSER_OUTPUT: Values: 498.2 500.4 501.8 531.0 544.8 28838.7 38213.6 38799.6 38854.4 38895.0 40871.4 40960.0 41397.2 41558.0 41570.2 7049.8 7195.4 8065.5 8208.6 8316.0 10385.2 10690.7 10693.0 10693.6 10700.0 10228.4 10343.2 10346.0 10361.4 10368.1 7186.1 7409.1 9978.1 10151.3 10194.6 9722.1 9836.2 9838.7 9841.3 9848.3 36159.0 36820.1 36841.0 36847.4 36856.8 2829.3 2881.6 2896.1 3393.0 3551.1 28164.2 28248.7 28521.7 28584.7 28816.3 2587.6 2599.3 2602.6 2631.4 2770.5 240105.0 241104.0 242156.8 242252.0 243038.0 53821.0 55638.2 60204.0 60903.7 62856.0 61740.7 61816.7 61877.0 62042.0 62061.0 14722.3 14956.6 14992.0 14999.2 15031.8 11152.2 11196.8 11258.3 11289.1 11289.4 19185.1 19608.2 19612.2 19624.0 19640.9 7885.1 8259.3 8323.5 8362.8 8446.2 799.7 857.9 917.2 943.6 975.8 27100.2 27137.2 27170.6 27328.5 27587.4 937.5 939.7 966.4 986.6 1070.9 13561.7 13959.7 14100.4 14205.4 14219.0 320261.0 325307.0 325532.0 327590.0 327624.0 49502.5 50075.0 50075.2 50127.7 50140.0 1185.5 1198.1 1198.3 1218.5 1221.4 26049.2 26579.7 26844.3 26861.6 27092.5 37308.0 37871.4 39717.2 42818.6 43693.8 240906.0 240931.0 243186.0 243558.0 243630.0 24877.4 30231.4 30760.2 30793.7 30798.1 29622.3 34955.7 35884.3 36362.0 36826.6 34467.7 34670.3 35019.8 35304.8 35455.3 33864.8 34092.1 34139.0 39027.4 40856.4 35043.5 35613.0 35722.1 35772.3 35879.0 28649.3 31073.5 31104.8 31243.0 31634.4 24714.5 24720.5 24770.5 25032.0 25066.4 05:19:35 INFO - 2015-10-24 05:19:35,902 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,902 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24562.94 Median:4146.39 stddev:79646.19 (1920.9%) stddev-sans-first:79848.87 05:19:35 INFO - 2015-10-24 05:19:35,903 DEBUG : BROWSER_OUTPUT: Values: 357.3 360.8 362.1 363.6 364.1 12815.3 13134.3 15261.5 16281.2 17472.1 15575.4 15690.6 15715.9 15908.7 15908.8 4587.6 4686.7 4690.0 4694.2 5062.6 3190.5 3272.0 4650.0 4694.6 4806.5 4715.2 4751.7 4754.7 4755.6 4774.6 4725.1 4799.0 4811.7 4831.2 4832.1 4142.5 4150.3 4154.8 4157.1 4159.8 12452.2 12588.7 13160.1 13189.9 13190.0 1535.8 1589.5 1651.3 1744.8 1746.6 9082.4 9317.4 9324.1 9407.7 9486.4 1580.2 1596.3 1603.9 1613.5 1617.4 52317.5 52685.7 53408.0 53598.5 54460.2 6250.3 6338.5 6476.4 6564.1 6618.3 7358.8 7362.3 7363.0 7363.6 7368.8 5334.6 5343.7 5360.6 5365.2 5376.1 5512.1 5536.6 5539.1 5542.1 5556.3 3049.5 3069.2 3099.3 3100.4 3116.7 1058.7 1095.8 1110.5 1149.1 1168.1 384.6 403.4 413.7 418.0 429.9 10100.5 10295.7 10500.1 10762.1 10865.3 395.1 400.5 428.5 442.5 443.0 5535.1 5844.3 6112.4 6329.5 6338.7 478993.0 481645.0 488216.0 488681.0 495700.0 57451.4 65763.3 68875.0 70255.3 71072.3 487.4 493.5 498.6 499.1 499.3 10062.6 10554.9 10891.5 10962.2 11156.9 31944.7 32161.4 32884.0 33546.5 33730.6 39791.2 39793.6 39873.0 40934.2 41273.6 7270.8 7512.8 7526.7 7689.1 7927.0 11617.7 11672.8 11798.9 11802.6 11827.3 10085.6 10184.6 10292.3 10314.6 10448.2 9761.2 10098.5 10155.4 10168.5 10351.7 9786.0 10344.9 10371.2 10617.2 10636.7 6768.4 6776.7 7788.9 7842.9 7850.0 10223.6 10289.4 10345.2 10363.2 10367.8 05:19:35 INFO - 2015-10-24 05:19:35,903 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,903 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6057.18 Median:4874.21 stddev:3444.13 (70.7%) stddev-sans-first:3435.44 05:19:35 INFO - 2015-10-24 05:19:35,903 DEBUG : BROWSER_OUTPUT: Values: 1327.3 1443.7 1606.3 1634.3 1789.9 7411.1 8502.2 8542.0 8562.1 8616.1 8535.3 8862.6 8870.3 8870.3 8880.8 2107.2 2133.0 2154.6 2156.6 2156.8 2182.9 2200.7 2244.0 2259.9 2261.8 2275.7 2278.0 2295.7 2311.7 2316.6 2215.3 2240.4 2246.3 2252.5 2255.9 2246.5 2256.4 2265.6 2265.8 2268.6 7322.5 8408.4 8444.6 8501.7 8503.2 4261.3 4726.4 4854.5 4858.7 4867.5 2820.9 2859.6 2965.9 3004.2 3014.1 829.8 867.3 873.2 915.6 924.1 13034.7 13089.1 13143.7 13163.8 13170.7 4563.7 4666.3 4684.7 4740.3 4742.3 10871.8 10917.3 10923.1 10928.5 10930.1 4865.7 4869.6 4878.8 4881.8 4885.2 4353.3 4373.0 4379.3 4390.1 4397.7 3752.0 3808.3 3833.8 3864.6 3875.9 1206.8 1207.7 1210.7 1214.7 1224.6 840.2 851.8 853.8 854.7 1146.8 8284.1 8285.0 8319.7 8352.7 8393.3 470.3 472.7 1128.5 1179.3 1202.8 5893.4 6231.3 6268.7 6296.7 6301.1 9947.4 11173.4 11225.3 11242.6 11251.4 10893.7 11012.9 11061.9 11173.2 11301.8 8142.4 8241.8 8245.5 8248.5 8249.5 8257.6 8275.5 8282.6 8340.1 8367.7 9594.9 9660.7 9667.7 9681.9 9750.3 9699.3 10042.9 10043.3 10063.8 10076.3 7031.3 7195.0 7197.1 7206.3 7241.1 8578.8 8615.1 8643.7 8651.4 8672.1 7817.3 7861.3 7899.6 8038.4 8087.0 7710.2 7713.0 7783.3 7822.7 8054.1 7618.2 7809.6 7857.4 7909.5 7914.6 7172.4 7194.6 7205.0 7206.2 7233.9 7049.6 7169.9 7302.7 7392.1 7425.0 05:19:35 INFO - 2015-10-24 05:19:35,903 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,903 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4478.32 Median:4982.74 stddev:1261.60 (25.3%) stddev-sans-first:1226.08 05:19:35 INFO - 2015-10-24 05:19:35,904 DEBUG : BROWSER_OUTPUT: Values: 328.3 330.7 334.9 341.8 343.3 5177.5 5194.1 5195.1 5195.5 5206.3 4943.8 5018.5 5022.3 5111.8 5114.5 4033.9 4044.8 4048.4 4052.3 4060.5 3872.2 4048.1 4049.6 4049.6 4049.7 3889.1 3984.5 4056.7 4057.5 4059.8 4025.0 4025.4 4034.1 4035.6 4040.4 3627.6 3629.4 3629.7 3630.0 3630.0 4907.8 4939.8 4940.5 4945.0 4947.0 4536.7 4575.2 4632.5 4633.4 4634.7 5364.1 5371.9 5373.3 5373.9 5376.8 3896.7 3897.4 3897.8 3904.7 3905.5 5623.7 5626.2 5637.0 5638.1 5642.5 3933.2 4016.1 4041.9 4043.3 4053.8 4089.3 4092.9 4093.4 4095.1 4097.4 4600.2 4607.4 4608.4 4609.4 4616.0 5342.9 5350.7 5353.4 5353.6 5354.2 1448.0 1464.8 1482.1 1482.6 1483.2 1031.0 1039.1 1040.3 1041.4 1041.8 4730.0 4733.4 4736.7 4738.1 4738.4 5525.3 5527.8 5533.9 5535.8 5536.6 4567.9 4620.5 4635.6 4726.8 4731.6 2989.7 3011.6 3022.8 3024.3 3027.8 5211.0 5212.7 5213.5 5214.3 5219.1 5627.2 5627.9 5628.7 5629.5 5634.0 5111.5 5180.6 5212.0 5214.5 5215.2 5485.0 5485.6 5491.2 5498.0 5500.0 5348.2 5349.4 5349.8 5352.0 5354.1 5110.7 5194.5 5197.7 5206.0 5209.8 3870.4 3912.3 3920.4 3920.7 3921.8 5457.2 5467.5 5481.1 5484.0 5486.0 5305.4 5310.0 5313.3 5315.0 5315.3 5175.4 5299.5 5300.8 5312.9 5323.5 5259.6 5298.7 5300.0 5300.0 5300.5 5369.9 5370.7 5371.7 5373.1 5407.9 5240.0 5242.0 5245.8 5247.8 5248.3 05:19:35 INFO - 2015-10-24 05:19:35,905 DEBUG : BROWSER_OUTPUT: 05:19:35 INFO - 2015-10-24 05:19:35,905 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:9941.98 Median:2981.03 stddev:10994.18 (368.8%) stddev-sans-first:11002.64 05:19:35 INFO - 2015-10-24 05:19:35,906 DEBUG : BROWSER_OUTPUT: Values: 601.4 689.0 756.9 761.3 790.7 10385.1 10563.9 10572.9 10592.8 10596.2 10084.3 11784.4 11971.3 11985.1 11990.4 622.9 626.4 777.2 790.7 791.2 758.5 787.7 790.1 790.2 791.4 815.3 825.5 828.7 829.8 831.7 640.8 651.0 797.9 824.8 825.2 596.0 596.7 597.1 597.9 599.0 10981.1 11024.9 11103.3 11119.5 11127.9 7826.8 7996.4 8085.6 8147.3 8171.1 7644.0 7762.8 7834.8 7908.3 7950.4 2473.3 2497.3 2502.8 2528.1 2548.3 31999.4 32252.0 32265.4 32289.0 32313.2 5831.5 5970.4 5978.6 6129.5 6194.1 9496.8 9519.5 9519.7 9528.7 9532.4 5082.7 5153.4 5171.8 5212.5 5380.9 3132.5 3146.2 3149.8 3157.3 3267.2 2379.6 2396.9 2410.7 2412.3 2414.6 392.5 393.2 399.3 403.4 403.5 395.9 423.5 436.3 457.2 460.2 6550.8 6620.0 6728.4 6894.7 6982.2 469.8 489.4 528.9 581.2 592.7 3207.5 3305.7 3361.8 3378.4 3387.0 42200.6 42448.6 42484.0 42517.0 42533.4 35198.6 35775.5 35813.6 35860.8 35921.0 30192.8 30399.2 30609.5 31064.4 31105.4 6546.0 6572.1 6613.4 6688.3 6704.2 22502.2 22893.2 23524.3 23659.6 24038.3 30330.0 30437.0 30542.5 30940.2 31307.4 6652.0 6696.5 6752.3 6913.4 7137.3 9883.3 10150.3 10283.2 10390.3 10462.5 9642.3 9709.6 9851.9 9855.1 10013.6 9236.8 9371.0 9447.9 9516.3 9878.3 9249.3 9865.8 10052.6 10098.8 10101.4 8119.6 8235.9 8247.5 8265.9 8274.8 7656.0 7927.2 8156.3 8163.4 8199.3 05:19:35 INFO - 2015-10-24 05:19:35,906 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:19:35 INFO - 2015-10-24 05:19:35,906 DEBUG : BROWSER_OUTPUT: 05:19:36 INFO - 2015-10-24 05:19:36,538 INFO : Browser exited with error code: 0 05:19:36 INFO - 2015-10-24 05:19:36,554 INFO : Completed test dromaeo_css (00:18:38) 05:19:36 INFO - 2015-10-24 05:19:36,554 INFO : Starting test kraken 05:19:36 INFO - 2015-10-24 05:19:36,554 DEBUG : operating with platform_type : linux_ 05:19:36 INFO - 2015-10-24 05:19:36,554 INFO : Initialising browser for kraken test... 05:19:36 INFO - 2015-10-24 05:19:36,560 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Qbhru/profile http://localhost:45606/getInfo.html 05:19:40 INFO - 2015-10-24 05:19:40,778 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:19:40 INFO - 2015-10-24 05:19:40,778 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:19:40 INFO - 2015-10-24 05:19:40,778 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:19:40 INFO - 2015-10-24 05:19:40,778 DEBUG : BROWSER_OUTPUT: __metrics 05:19:40 INFO - 2015-10-24 05:19:40,875 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: console.error: 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: Stack: 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:19:41 INFO - 2015-10-24 05:19:41,582 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 05:19:41 INFO - 2015-10-24 05:19:41,583 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 05:19:41 INFO - 2015-10-24 05:19:41,583 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 05:19:41 INFO - 2015-10-24 05:19:41,583 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6079.03 05:21:17 INFO - RETURN: kraken: 1495.20 05:21:17 INFO - RETURN: v8_7: 19977.49 05:21:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19977.49"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1495.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6079.03"}}} 05:21:17 INFO - Return code: 0 05:21:17 INFO - # TBPL SUCCESS # 05:21:17 INFO - Running post-action listener: _resource_record_post_action 05:21:17 INFO - Running post-run listener: _resource_record_post_run 05:21:18 INFO - Total resource usage - Wall time: 1234s; CPU: 13.0%; Read bytes: 8019968; Write bytes: 392036352; Read time: 1816; Write time: 340920 05:21:18 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 60620800; Read time: 0; Write time: 33256 05:21:18 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 5865472; Write bytes: 280379392; Read time: 1380; Write time: 219548 05:21:18 INFO - Running post-run listener: _upload_blobber_files 05:21:18 INFO - Blob upload gear active. 05:21:18 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:21:18 INFO - Copying logs to upload dir... 05:21:18 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.901322 ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-10-24 05:21:18.372832) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-24 05:21:18.376387) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009282 build_url: 'https://queue.taskcluster.net/v1/task/1CuxR8JfRf6mUVdbXt1NFw/artifacts/public/build/firefox-44.0a1.en-US.linux-x86_64.tar.bz2' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-24 05:21:18.428159) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:21:18.428477) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=b4e1481c633df10bed3f4cc0000001c7-1445685241.185632-393978477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004401 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-24 05:21:18.477860) ========= ========= Started reboot slave lost (results: 0, elapsed: 4 secs) (at 2015-10-24 05:21:18.478174) ========= ========= Finished reboot slave lost (results: 0, elapsed: 4 secs) (at 2015-10-24 05:21:22.712290) =========