builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1450183297.62 results: success (0) buildid: 20151215031712 builduid: b12b462f948243219d794babc079f349 revision: 749f9328dd769409fd4c7e105202829e239910ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.617076) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.617538) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.617826) ========= 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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006194 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.664357) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.664659) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.777999) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.778409) ========= 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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.845681) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.846340) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.846733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:37.847060) ========= 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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-15 04:41:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 227M=0s 2015-12-15 04:41:38 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.249560 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:38.112468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:38.112844) ========= 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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013425 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:38.162535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 04:41:38.162896) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 749f9328dd769409fd4c7e105202829e239910ac --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 749f9328dd769409fd4c7e105202829e239910ac --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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-15 04:41:38,220 truncating revision to first 12 chars 2015-12-15 04:41:38,220 Setting DEBUG logging. 2015-12-15 04:41:38,220 attempt 1/10 2015-12-15 04:41:38,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/749f9328dd76?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-15 04:41:39,299 unpacking tar archive at: mozilla-central-749f9328dd76/testing/mozharness/ program finished with exit code 0 elapsedTime=1.363862 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 04:41:39.544517) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:39.544887) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:39.557576) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:39.557909) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 04:41:39.558503) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2015-12-15 04:41:39.558806) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:41:39 INFO - MultiFileLogger online at 20151215 04:41:39 in /builds/slave/test 04:41:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 04:41:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:41:39 INFO - {'append_to_log': False, 04:41:39 INFO - 'base_work_dir': '/builds/slave/test', 04:41:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:41:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:41:39 INFO - 'branch': 'Firefox-Non-PGO', 04:41:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:41:39 INFO - 'config_files': ('talos/linux_config.py',), 04:41:39 INFO - 'default_actions': ('clobber', 04:41:39 INFO - 'read-buildbot-config', 04:41:39 INFO - 'download-and-extract', 04:41:39 INFO - 'populate-webroot', 04:41:39 INFO - 'create-virtualenv', 04:41:39 INFO - 'install', 04:41:39 INFO - 'run-tests'), 04:41:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:41:39 INFO - 'download_minidump_stackwalk': True, 04:41:39 INFO - 'download_symbols': 'ondemand', 04:41:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:41:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:41:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:41:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:41:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:41:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:41:39 INFO - 'installer_path': 'installer.exe', 04:41:39 INFO - 'log_level': 'info', 04:41:39 INFO - 'log_name': 'talos', 04:41:39 INFO - 'log_to_console': True, 04:41:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:41:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:41:39 INFO - 'opt_config_files': (), 04:41:39 INFO - 'pip_index': False, 04:41:39 INFO - 'sps_profile': False, 04:41:39 INFO - 'sps_profile_interval': 0, 04:41:39 INFO - 'suite': 'dromaeojs-e10s', 04:41:39 INFO - 'system_bits': '64', 04:41:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:41:39 INFO - 'title': 'talos-linux64-ix-045', 04:41:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:41:39 INFO - 'use_talos_json': True, 04:41:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:41:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:41:39 INFO - 'work_dir': 'build'} 04:41:39 INFO - ##### 04:41:39 INFO - ##### Running clobber step. 04:41:39 INFO - ##### 04:41:39 INFO - Running pre-action listener: _resource_record_pre_action 04:41:39 INFO - Running main action method: clobber 04:41:39 INFO - rmtree: /builds/slave/test/build 04:41:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:41:40 INFO - Running post-action listener: _resource_record_post_action 04:41:40 INFO - ##### 04:41:40 INFO - ##### Running read-buildbot-config step. 04:41:40 INFO - ##### 04:41:40 INFO - Running pre-action listener: _resource_record_pre_action 04:41:40 INFO - Running main action method: read_buildbot_config 04:41:40 INFO - Using buildbot properties: 04:41:40 INFO - { 04:41:40 INFO - "properties": { 04:41:40 INFO - "buildnumber": 172, 04:41:40 INFO - "product": "firefox", 04:41:40 INFO - "script_repo_revision": "production", 04:41:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:41:40 INFO - "repository": "", 04:41:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:41:40 INFO - "buildid": "20151215031712", 04:41:40 INFO - "slavename": "talos-linux64-ix-045", 04:41:40 INFO - "pgo_build": "False", 04:41:40 INFO - "basedir": "/builds/slave/test", 04:41:40 INFO - "project": "", 04:41:40 INFO - "platform": "ubuntu64_hw", 04:41:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:41:40 INFO - "slavebuilddir": "test", 04:41:40 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:41:40 INFO - "branch": "mozilla-central", 04:41:40 INFO - "repo_path": "mozilla-central", 04:41:40 INFO - "stage_platform": "linux64", 04:41:40 INFO - "builduid": "b12b462f948243219d794babc079f349", 04:41:40 INFO - "revision": "749f9328dd769409fd4c7e105202829e239910ac" 04:41:40 INFO - }, 04:41:40 INFO - "sourcestamp": { 04:41:40 INFO - "repository": "", 04:41:40 INFO - "hasPatch": false, 04:41:40 INFO - "project": "", 04:41:40 INFO - "branch": "mozilla-central-linux64-talos", 04:41:40 INFO - "changes": [ 04:41:40 INFO - { 04:41:40 INFO - "category": null, 04:41:40 INFO - "files": [ 04:41:40 INFO - { 04:41:40 INFO - "url": null, 04:41:40 INFO - "name": "https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 04:41:40 INFO - } 04:41:40 INFO - ], 04:41:40 INFO - "repository": "", 04:41:40 INFO - "rev": "749f9328dd769409fd4c7e105202829e239910ac", 04:41:40 INFO - "who": "cbook@mozilla.com", 04:41:40 INFO - "when": 1450183266, 04:41:40 INFO - "number": 7361769, 04:41:40 INFO - "comments": "merge mozilla-inbound to mozilla-central a=merge", 04:41:40 INFO - "project": "", 04:41:40 INFO - "at": "Tue 15 Dec 2015 04:41:06", 04:41:40 INFO - "branch": "mozilla-central-linux64-talos", 04:41:40 INFO - "revlink": "", 04:41:40 INFO - "properties": [ 04:41:40 INFO - [ 04:41:40 INFO - "buildid", 04:41:40 INFO - "20151215031712", 04:41:40 INFO - "Change" 04:41:40 INFO - ], 04:41:40 INFO - [ 04:41:40 INFO - "builduid", 04:41:40 INFO - "b12b462f948243219d794babc079f349", 04:41:40 INFO - "Change" 04:41:40 INFO - ], 04:41:40 INFO - [ 04:41:40 INFO - "pgo_build", 04:41:40 INFO - "False", 04:41:40 INFO - "Change" 04:41:40 INFO - ] 04:41:40 INFO - ], 04:41:40 INFO - "revision": "749f9328dd769409fd4c7e105202829e239910ac" 04:41:40 INFO - } 04:41:40 INFO - ], 04:41:40 INFO - "revision": "749f9328dd769409fd4c7e105202829e239910ac" 04:41:40 INFO - } 04:41:40 INFO - } 04:41:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 04:41:40 INFO - Running post-action listener: _resource_record_post_action 04:41:40 INFO - ##### 04:41:40 INFO - ##### Running download-and-extract step. 04:41:40 INFO - ##### 04:41:40 INFO - Running pre-action listener: _resource_record_pre_action 04:41:40 INFO - Running main action method: download_and_extract 04:41:40 INFO - mkdir: /builds/slave/test/build/tests 04:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:40 INFO - https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/test_packages.json 04:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/test_packages.json 04:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:41:43 INFO - Downloaded 1302 bytes. 04:41:43 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:41:43 INFO - Using the following test package requirements: 04:41:43 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:41:43 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:41:43 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'jsshell-linux-x86_64.zip'], 04:41:43 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:41:43 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:41:43 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:41:43 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:41:43 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:41:43 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:41:43 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:41:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:41:43 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:43 INFO - https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:41:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:41:44 INFO - Downloaded 21863113 bytes. 04:41:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:41:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:41:45 INFO - Return code: 0 04:41:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:45 INFO - https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:41:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:41:48 INFO - Downloaded 21863113 bytes. 04:41:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:41:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:41:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:41:50 INFO - Return code: 0 04:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:50 INFO - https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:41:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:41:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:41:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:41:51 INFO - Downloaded 11132052 bytes. 04:41:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:41:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:41:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:41:51 INFO - Return code: 0 04:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:51 INFO - https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:41:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:41:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:41:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:41:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:41:53 INFO - Downloaded 57735862 bytes. 04:41:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:41:53 INFO - mkdir: /builds/slave/test/properties 04:41:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:41:53 INFO - Writing to file /builds/slave/test/properties/build_url 04:41:53 INFO - Contents: 04:41:53 INFO - build_url:https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:41:53 INFO - Running post-action listener: _resource_record_post_action 04:41:53 INFO - Running post-action listener: set_extra_try_arguments 04:41:53 INFO - ##### 04:41:53 INFO - ##### Running populate-webroot step. 04:41:53 INFO - ##### 04:41:53 INFO - Running pre-action listener: _resource_record_pre_action 04:41:53 INFO - Running main action method: populate_webroot 04:41:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:41:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:41:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:41:53 INFO - '--tppagecycles', 04:41:53 INFO - '7'], 04:41:53 INFO - 'tests': ['tsvgm']}}, 04:41:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:41:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:41:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:41:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:41:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:41:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:41:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'tests': ['damp', 'tps']}, 04:41:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['damp', 'tps']}, 04:41:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:41:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:41:53 INFO - 'other': {'tests': ['a11yr', 04:41:53 INFO - 'ts_paint', 04:41:53 INFO - 'tpaint', 04:41:53 INFO - 'sessionrestore', 04:41:53 INFO - 'sessionrestore_no_auto_restore']}, 04:41:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['a11yr', 04:41:53 INFO - 'ts_paint', 04:41:53 INFO - 'tpaint', 04:41:53 INFO - 'sessionrestore', 04:41:53 INFO - 'sessionrestore_no_auto_restore']}, 04:41:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['a11yr', 04:41:53 INFO - 'ts_paint', 04:41:53 INFO - 'tpaint', 04:41:53 INFO - 'sessionrestore', 04:41:53 INFO - 'sessionrestore_no_auto_restore']}, 04:41:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['a11yr', 04:41:53 INFO - 'ts_paint', 04:41:53 INFO - 'tpaint', 04:41:53 INFO - 'sessionrestore', 04:41:53 INFO - 'sessionrestore_no_auto_restore']}, 04:41:53 INFO - 'other_l64': {'tests': ['a11yr', 04:41:53 INFO - 'ts_paint', 04:41:53 INFO - 'tpaint', 04:41:53 INFO - 'sessionrestore', 04:41:53 INFO - 'sessionrestore_no_auto_restore']}, 04:41:53 INFO - 'other_nol64': {'tests': ['a11yr', 04:41:53 INFO - 'ts_paint', 04:41:53 INFO - 'tpaint', 04:41:53 INFO - 'sessionrestore', 04:41:53 INFO - 'sessionrestore_no_auto_restore']}, 04:41:53 INFO - 'svgr': {'tests': ['tsvgx', 04:41:53 INFO - 'tsvgr_opacity', 04:41:53 INFO - 'tart', 04:41:53 INFO - 'tscrollx', 04:41:53 INFO - 'cart']}, 04:41:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['tsvgx', 04:41:53 INFO - 'tsvgr_opacity', 04:41:53 INFO - 'tart', 04:41:53 INFO - 'tscrollx', 04:41:53 INFO - 'cart']}, 04:41:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'tests': ['tp5o']}, 04:41:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'talos_options': ['--e10s'], 04:41:53 INFO - 'tests': ['tp5o']}, 04:41:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'talos_options': ['--xperf_path', 04:41:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:41:53 INFO - 'tests': ['tp5n']}, 04:41:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:53 INFO - 'talos_options': ['--e10s', 04:41:53 INFO - '--xperf_path', 04:41:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:41:53 INFO - 'tests': ['tp5n']}}, 04:41:53 INFO - 'talos.zip': {'path': '', 04:41:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:41:53 INFO - Running post-action listener: _resource_record_post_action 04:41:53 INFO - ##### 04:41:53 INFO - ##### Running create-virtualenv step. 04:41:53 INFO - ##### 04:41:53 INFO - Running pre-action listener: _resource_record_pre_action 04:41:53 INFO - Running main action method: create_virtualenv 04:41:53 INFO - Creating virtualenv /builds/slave/test/build/venv 04:41:53 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 04:41:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:41:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:41:53 INFO - Using real prefix '/usr' 04:41:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:41:55 INFO - Installing distribute.............................................................................................................................................................................................done. 04:41:57 INFO - Installing pip.................done. 04:41:57 INFO - Return code: 0 04:41:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:57 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:41:57 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 04:41:57 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 04:41:57 INFO - Using env: {'DISPLAY': ':0', 04:41:57 INFO - 'HOME': '/home/cltbld', 04:41:57 INFO - 'LANG': 'en_US.UTF-8', 04:41:57 INFO - 'LANGUAGE': 'en_US:en', 04:41:57 INFO - 'LOGNAME': 'cltbld', 04:41:57 INFO - 'MAIL': '/var/mail/cltbld', 04:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:57 INFO - 'MOZ_NO_REMOTE': '1', 04:41:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:41:57 INFO - 'NO_EM_RESTART': '1', 04:41:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:57 INFO - 'PWD': '/builds/slave/test', 04:41:57 INFO - 'SHELL': '/bin/bash', 04:41:57 INFO - 'SHLVL': '1', 04:41:57 INFO - 'TERM': 'linux', 04:41:57 INFO - 'TMOUT': '86400', 04:41:57 INFO - 'USER': 'cltbld', 04:41:57 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:57 INFO - '_': '/tools/buildbot/bin/python'} 04:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:41:57 INFO - Cleaning up... 04:41:57 INFO - Return code: 0 04:41:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:57 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:41:57 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 04:41:57 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 04:41:57 INFO - Using env: {'DISPLAY': ':0', 04:41:57 INFO - 'HOME': '/home/cltbld', 04:41:57 INFO - 'LANG': 'en_US.UTF-8', 04:41:57 INFO - 'LANGUAGE': 'en_US:en', 04:41:57 INFO - 'LOGNAME': 'cltbld', 04:41:57 INFO - 'MAIL': '/var/mail/cltbld', 04:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:57 INFO - 'MOZ_NO_REMOTE': '1', 04:41:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:41:57 INFO - 'NO_EM_RESTART': '1', 04:41:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:57 INFO - 'PWD': '/builds/slave/test', 04:41:57 INFO - 'SHELL': '/bin/bash', 04:41:57 INFO - 'SHLVL': '1', 04:41:57 INFO - 'TERM': 'linux', 04:41:57 INFO - 'TMOUT': '86400', 04:41:57 INFO - 'USER': 'cltbld', 04:41:57 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:57 INFO - '_': '/tools/buildbot/bin/python'} 04:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:57 INFO - Downloading/unpacking psutil>=0.7.1 04:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:59 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:41:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:41:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:41:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:41:59 INFO - Installing collected packages: psutil 04:41:59 INFO - Running setup.py install for psutil 04:41:59 INFO - building 'psutil._psutil_linux' extension 04:41:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:42:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:42:00 INFO - building 'psutil._psutil_posix' extension 04:42:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:42:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:42:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:01 INFO - Successfully installed psutil 04:42:01 INFO - Cleaning up... 04:42:01 INFO - Return code: 0 04:42:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:01 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:01 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 04:42:01 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 04:42:01 INFO - Using env: {'DISPLAY': ':0', 04:42:01 INFO - 'HOME': '/home/cltbld', 04:42:01 INFO - 'LANG': 'en_US.UTF-8', 04:42:01 INFO - 'LANGUAGE': 'en_US:en', 04:42:01 INFO - 'LOGNAME': 'cltbld', 04:42:01 INFO - 'MAIL': '/var/mail/cltbld', 04:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:01 INFO - 'MOZ_NO_REMOTE': '1', 04:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:01 INFO - 'NO_EM_RESTART': '1', 04:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:01 INFO - 'PWD': '/builds/slave/test', 04:42:01 INFO - 'SHELL': '/bin/bash', 04:42:01 INFO - 'SHLVL': '1', 04:42:01 INFO - 'TERM': 'linux', 04:42:01 INFO - 'TMOUT': '86400', 04:42:01 INFO - 'USER': 'cltbld', 04:42:01 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:01 INFO - '_': '/tools/buildbot/bin/python'} 04:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:42:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:42:03 INFO - Installing collected packages: mozsystemmonitor 04:42:03 INFO - Running setup.py install for mozsystemmonitor 04:42:03 INFO - Successfully installed mozsystemmonitor 04:42:03 INFO - Cleaning up... 04:42:03 INFO - Return code: 0 04:42:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:03 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:03 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 04:42:03 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 04:42:03 INFO - Using env: {'DISPLAY': ':0', 04:42:03 INFO - 'HOME': '/home/cltbld', 04:42:03 INFO - 'LANG': 'en_US.UTF-8', 04:42:03 INFO - 'LANGUAGE': 'en_US:en', 04:42:03 INFO - 'LOGNAME': 'cltbld', 04:42:03 INFO - 'MAIL': '/var/mail/cltbld', 04:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:03 INFO - 'MOZ_NO_REMOTE': '1', 04:42:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:03 INFO - 'NO_EM_RESTART': '1', 04:42:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:03 INFO - 'PWD': '/builds/slave/test', 04:42:03 INFO - 'SHELL': '/bin/bash', 04:42:03 INFO - 'SHLVL': '1', 04:42:03 INFO - 'TERM': 'linux', 04:42:03 INFO - 'TMOUT': '86400', 04:42:03 INFO - 'USER': 'cltbld', 04:42:03 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:03 INFO - '_': '/tools/buildbot/bin/python'} 04:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:03 INFO - Downloading/unpacking blobuploader==1.2.4 04:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:05 INFO - Downloading blobuploader-1.2.4.tar.gz 04:42:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:42:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:42:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:42:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:42:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:05 INFO - Downloading docopt-0.6.1.tar.gz 04:42:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:42:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:42:06 INFO - Installing collected packages: blobuploader, requests, docopt 04:42:06 INFO - Running setup.py install for blobuploader 04:42:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:42:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:42:06 INFO - Running setup.py install for requests 04:42:06 INFO - Running setup.py install for docopt 04:42:06 INFO - Successfully installed blobuploader requests docopt 04:42:06 INFO - Cleaning up... 04:42:06 INFO - Return code: 0 04:42:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:06 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:06 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 04:42:06 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 04:42:06 INFO - Using env: {'DISPLAY': ':0', 04:42:06 INFO - 'HOME': '/home/cltbld', 04:42:06 INFO - 'LANG': 'en_US.UTF-8', 04:42:06 INFO - 'LANGUAGE': 'en_US:en', 04:42:06 INFO - 'LOGNAME': 'cltbld', 04:42:06 INFO - 'MAIL': '/var/mail/cltbld', 04:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:06 INFO - 'MOZ_NO_REMOTE': '1', 04:42:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:06 INFO - 'NO_EM_RESTART': '1', 04:42:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:06 INFO - 'PWD': '/builds/slave/test', 04:42:06 INFO - 'SHELL': '/bin/bash', 04:42:06 INFO - 'SHLVL': '1', 04:42:06 INFO - 'TERM': 'linux', 04:42:06 INFO - 'TMOUT': '86400', 04:42:06 INFO - 'USER': 'cltbld', 04:42:06 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:06 INFO - '_': '/tools/buildbot/bin/python'} 04:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:06 INFO - Running setup.py (path:/tmp/pip-zzk8QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:06 INFO - Running setup.py (path:/tmp/pip-WAWr4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:06 INFO - Running setup.py (path:/tmp/pip-eGazbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:07 INFO - Running setup.py (path:/tmp/pip-zyPLX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:07 INFO - Running setup.py (path:/tmp/pip-d9IWM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:07 INFO - Running setup.py (path:/tmp/pip-ZGRznF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:07 INFO - Running setup.py (path:/tmp/pip-ADi4GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:07 INFO - Running setup.py (path:/tmp/pip-iyJWwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:07 INFO - Running setup.py (path:/tmp/pip-dh4qaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:07 INFO - Running setup.py (path:/tmp/pip-ZGUEHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:07 INFO - Running setup.py (path:/tmp/pip-p37h0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:07 INFO - Running setup.py (path:/tmp/pip-8TNPYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:07 INFO - Running setup.py (path:/tmp/pip-xihXCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:07 INFO - Running setup.py (path:/tmp/pip-yzLt7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:07 INFO - Running setup.py (path:/tmp/pip-F_6ggG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:07 INFO - Running setup.py (path:/tmp/pip-g4TkHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:07 INFO - Running setup.py (path:/tmp/pip-p2rhTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:42:08 INFO - Running setup.py install for manifestparser 04:42:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Running setup.py install for mozcrash 04:42:08 INFO - Running setup.py install for mozdebug 04:42:08 INFO - Running setup.py install for mozdevice 04:42:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Running setup.py install for mozfile 04:42:08 INFO - Running setup.py install for mozhttpd 04:42:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Running setup.py install for mozinfo 04:42:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Running setup.py install for mozInstall 04:42:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:42:08 INFO - Running setup.py install for mozleak 04:42:08 INFO - Running setup.py install for mozlog 04:42:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Running setup.py install for moznetwork 04:42:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Running setup.py install for mozprocess 04:42:09 INFO - Running setup.py install for mozprofile 04:42:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Running setup.py install for mozrunner 04:42:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Running setup.py install for mozscreenshot 04:42:09 INFO - Running setup.py install for moztest 04:42:09 INFO - Running setup.py install for mozversion 04:42:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:42:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:42:09 INFO - Cleaning up... 04:42:09 INFO - Return code: 0 04:42:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:09 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:09 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 04:42:09 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 04:42:09 INFO - Using env: {'DISPLAY': ':0', 04:42:09 INFO - 'HOME': '/home/cltbld', 04:42:09 INFO - 'LANG': 'en_US.UTF-8', 04:42:09 INFO - 'LANGUAGE': 'en_US:en', 04:42:09 INFO - 'LOGNAME': 'cltbld', 04:42:09 INFO - 'MAIL': '/var/mail/cltbld', 04:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:09 INFO - 'MOZ_NO_REMOTE': '1', 04:42:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:09 INFO - 'NO_EM_RESTART': '1', 04:42:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:09 INFO - 'PWD': '/builds/slave/test', 04:42:09 INFO - 'SHELL': '/bin/bash', 04:42:09 INFO - 'SHLVL': '1', 04:42:09 INFO - 'TERM': 'linux', 04:42:09 INFO - 'TMOUT': '86400', 04:42:09 INFO - 'USER': 'cltbld', 04:42:09 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:09 INFO - '_': '/tools/buildbot/bin/python'} 04:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:10 INFO - Running setup.py (path:/tmp/pip-bCDNIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:10 INFO - Running setup.py (path:/tmp/pip-dH_ny6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:10 INFO - Running setup.py (path:/tmp/pip-i8oKbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:10 INFO - Running setup.py (path:/tmp/pip-tC5d2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:10 INFO - Running setup.py (path:/tmp/pip-D5dMpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:10 INFO - Running setup.py (path:/tmp/pip-vqiJkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:10 INFO - Running setup.py (path:/tmp/pip-KPmiPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:10 INFO - Running setup.py (path:/tmp/pip-0e2cXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:10 INFO - Running setup.py (path:/tmp/pip-22yltt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:10 INFO - Running setup.py (path:/tmp/pip-3dB9hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:10 INFO - Running setup.py (path:/tmp/pip-hObXHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:10 INFO - Running setup.py (path:/tmp/pip-14Fa2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:10 INFO - Running setup.py (path:/tmp/pip-TxjAj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:10 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)) 04:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:10 INFO - Running setup.py (path:/tmp/pip-bbioCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:11 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)) 04:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:11 INFO - Running setup.py (path:/tmp/pip-qEjESi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:11 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)) 04:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:11 INFO - Running setup.py (path:/tmp/pip-F4CPND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:11 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)) 04:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:11 INFO - Running setup.py (path:/tmp/pip-8EIUrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:11 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)) 04:42:11 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)) 04:42:11 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)) 04:42:11 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)) 04:42:11 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:42:11 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:42:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:12 INFO - Downloading blessings-1.5.1.tar.gz 04:42:12 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 04:42:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:42:13 INFO - Installing collected packages: blessings 04:42:13 INFO - Running setup.py install for blessings 04:42:13 INFO - Successfully installed blessings 04:42:13 INFO - Cleaning up... 04:42:13 INFO - Return code: 0 04:42:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:13 INFO - Reading from file tmpfile_stdout 04:42:13 INFO - Current package versions: 04:42:13 INFO - argparse == 1.2.1 04:42:13 INFO - blessings == 1.5.1 04:42:13 INFO - blobuploader == 1.2.4 04:42:13 INFO - docopt == 0.6.1 04:42:13 INFO - manifestparser == 1.1 04:42:13 INFO - mozInstall == 1.12 04:42:13 INFO - mozcrash == 0.16 04:42:13 INFO - mozdebug == 0.1 04:42:13 INFO - mozdevice == 0.47 04:42:13 INFO - mozfile == 1.2 04:42:13 INFO - mozhttpd == 0.7 04:42:13 INFO - mozinfo == 0.9 04:42:13 INFO - mozleak == 0.1 04:42:13 INFO - mozlog == 3.1 04:42:13 INFO - moznetwork == 0.27 04:42:13 INFO - mozprocess == 0.22 04:42:13 INFO - mozprofile == 0.27 04:42:13 INFO - mozrunner == 6.11 04:42:13 INFO - mozscreenshot == 0.1 04:42:13 INFO - mozsystemmonitor == 0.0 04:42:13 INFO - moztest == 0.7 04:42:13 INFO - mozversion == 1.4 04:42:13 INFO - psutil == 3.1.1 04:42:13 INFO - requests == 1.2.3 04:42:13 INFO - wsgiref == 0.1.2 04:42:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:13 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 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:13 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 04:42:13 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 04:42:13 INFO - Using env: {'DISPLAY': ':0', 04:42:13 INFO - 'HOME': '/home/cltbld', 04:42:13 INFO - 'LANG': 'en_US.UTF-8', 04:42:13 INFO - 'LANGUAGE': 'en_US:en', 04:42:13 INFO - 'LOGNAME': 'cltbld', 04:42:13 INFO - 'MAIL': '/var/mail/cltbld', 04:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:13 INFO - 'MOZ_NO_REMOTE': '1', 04:42:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:13 INFO - 'NO_EM_RESTART': '1', 04:42:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:13 INFO - 'PWD': '/builds/slave/test', 04:42:13 INFO - 'SHELL': '/bin/bash', 04:42:13 INFO - 'SHLVL': '1', 04:42:13 INFO - 'TERM': 'linux', 04:42:13 INFO - 'TMOUT': '86400', 04:42:13 INFO - 'USER': 'cltbld', 04:42:13 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:13 INFO - '_': '/tools/buildbot/bin/python'} 04:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 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)) 04:42:13 INFO - Cleaning up... 04:42:13 INFO - Return code: 0 04:42:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:13 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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:13 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', 'jsonschema'] in /builds/slave/test/build 04:42:13 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 jsonschema 04:42:13 INFO - Using env: {'DISPLAY': ':0', 04:42:13 INFO - 'HOME': '/home/cltbld', 04:42:13 INFO - 'LANG': 'en_US.UTF-8', 04:42:13 INFO - 'LANGUAGE': 'en_US:en', 04:42:13 INFO - 'LOGNAME': 'cltbld', 04:42:13 INFO - 'MAIL': '/var/mail/cltbld', 04:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:13 INFO - 'MOZ_NO_REMOTE': '1', 04:42:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:13 INFO - 'NO_EM_RESTART': '1', 04:42:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:13 INFO - 'PWD': '/builds/slave/test', 04:42:13 INFO - 'SHELL': '/bin/bash', 04:42:13 INFO - 'SHLVL': '1', 04:42:13 INFO - 'TERM': 'linux', 04:42:13 INFO - 'TMOUT': '86400', 04:42:13 INFO - 'USER': 'cltbld', 04:42:13 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:13 INFO - '_': '/tools/buildbot/bin/python'} 04:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:13 INFO - Downloading/unpacking jsonschema 04:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:42:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 04:42:15 INFO - Downloading/unpacking functools32 (from jsonschema) 04:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:15 INFO - Downloading functools32-3.2.3-2.tar.gz 04:42:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:42:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:42:15 INFO - warning: no files found matching '*.txt' 04:42:15 INFO - no previously-included directories found matching 'build' 04:42:15 INFO - no previously-included directories found matching 'dist' 04:42:15 INFO - no previously-included directories found matching '.git*' 04:42:15 INFO - Installing collected packages: jsonschema, functools32 04:42:15 INFO - Running setup.py install for functools32 04:42:15 INFO - warning: no files found matching '*.txt' 04:42:15 INFO - no previously-included directories found matching 'build' 04:42:15 INFO - no previously-included directories found matching 'dist' 04:42:15 INFO - no previously-included directories found matching '.git*' 04:42:15 INFO - Successfully installed jsonschema functools32 04:42:15 INFO - Cleaning up... 04:42:16 INFO - Return code: 0 04:42:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:16 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcd2a83f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, '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': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', '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 04:42:16 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', 'flake8'] in /builds/slave/test/build 04:42:16 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 flake8 04:42:16 INFO - Using env: {'DISPLAY': ':0', 04:42:16 INFO - 'HOME': '/home/cltbld', 04:42:16 INFO - 'LANG': 'en_US.UTF-8', 04:42:16 INFO - 'LANGUAGE': 'en_US:en', 04:42:16 INFO - 'LOGNAME': 'cltbld', 04:42:16 INFO - 'MAIL': '/var/mail/cltbld', 04:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:16 INFO - 'MOZ_NO_REMOTE': '1', 04:42:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:16 INFO - 'NO_EM_RESTART': '1', 04:42:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:16 INFO - 'PWD': '/builds/slave/test', 04:42:16 INFO - 'SHELL': '/bin/bash', 04:42:16 INFO - 'SHLVL': '1', 04:42:16 INFO - 'TERM': 'linux', 04:42:16 INFO - 'TMOUT': '86400', 04:42:16 INFO - 'USER': 'cltbld', 04:42:16 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:16 INFO - '_': '/tools/buildbot/bin/python'} 04:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:16 INFO - Downloading/unpacking flake8 04:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:42:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 04:42:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:42:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 04:42:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 04:42:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:42:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:42:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:42:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:42:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:42:18 INFO - no previously-included directories found matching 'docs/_build' 04:42:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:42:18 INFO - Running setup.py install for pep8 04:42:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:42:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:42:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:42:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:42:18 INFO - no previously-included directories found matching 'docs/_build' 04:42:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:42:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:42:18 INFO - Cleaning up... 04:42:18 INFO - Return code: 0 04:42:18 INFO - Running post-action listener: _resource_record_post_action 04:42:18 INFO - Running post-action listener: _start_resource_monitoring 04:42:18 INFO - Starting resource monitoring. 04:42:18 INFO - ##### 04:42:18 INFO - ##### Running install step. 04:42:18 INFO - ##### 04:42:18 INFO - Running pre-action listener: _resource_record_pre_action 04:42:18 INFO - Running main action method: install 04:42:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:19 INFO - Reading from file tmpfile_stdout 04:42:19 INFO - Detecting whether we're running mozinstall >=1.0... 04:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:42:19 INFO - Reading from file tmpfile_stdout 04:42:19 INFO - Output received: 04:42:19 INFO - Usage: mozinstall [options] installer 04:42:19 INFO - Options: 04:42:19 INFO - -h, --help show this help message and exit 04:42:19 INFO - -d DEST, --destination=DEST 04:42:19 INFO - Directory to install application into. [default: 04:42:19 INFO - "/builds/slave/test"] 04:42:19 INFO - --app=APP Application being installed. [default: firefox] 04:42:19 INFO - mkdir: /builds/slave/test/build/application 04:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:42:33 INFO - Reading from file tmpfile_stdout 04:42:33 INFO - Output received: 04:42:33 INFO - /builds/slave/test/build/application/firefox/firefox 04:42:33 INFO - Running post-action listener: _resource_record_post_action 04:42:33 INFO - ##### 04:42:33 INFO - ##### Running run-tests step. 04:42:33 INFO - ##### 04:42:33 INFO - Running pre-action listener: _resource_record_pre_action 04:42:33 INFO - Running main action method: run_tests 04:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:42:33 INFO - Python 2.7.3 04:42:33 INFO - Return code: 0 04:42:33 INFO - grabbing minidump binary from tooltool 04:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:33 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 0x2c480b0>, '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 0x2c48fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c49440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:42:33 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 04:42:33 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 04:42:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:42:33 INFO - Return code: 0 04:42:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:42:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:42:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:42:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:42:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:42:34 INFO - Return code: 0 04:42:34 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost'] in /builds/slave/test/build 04:42:34 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost 04:42:34 INFO - Using env: {'DISPLAY': ':0', 04:42:34 INFO - 'HOME': '/home/cltbld', 04:42:34 INFO - 'LANG': 'en_US.UTF-8', 04:42:34 INFO - 'LANGUAGE': 'en_US:en', 04:42:34 INFO - 'LOGNAME': 'cltbld', 04:42:34 INFO - 'MAIL': '/var/mail/cltbld', 04:42:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:42:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:34 INFO - 'MOZ_NO_REMOTE': '1', 04:42:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:42:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:34 INFO - 'NO_EM_RESTART': '1', 04:42:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:34 INFO - 'PWD': '/builds/slave/test', 04:42:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:42:34 INFO - 'SHELL': '/bin/bash', 04:42:34 INFO - 'SHLVL': '1', 04:42:34 INFO - 'TERM': 'linux', 04:42:34 INFO - 'TMOUT': '86400', 04:42:34 INFO - 'USER': 'cltbld', 04:42:34 INFO - 'XDG_SESSION_COOKIE': '61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847', 04:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:34 INFO - '_': '/tools/buildbot/bin/python'} 04:42:34 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost'] with output_timeout 3600 04:42:34 INFO - mozversion INFO | application_buildid: 20151215031712 04:42:34 INFO - mozversion INFO | application_changeset: 749f9328dd769409fd4c7e105202829e239910ac 04:42:34 INFO - mozversion INFO | application_display_name: Nightly 04:42:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:42:34 INFO - mozversion INFO | application_name: Firefox 04:42:34 INFO - mozversion INFO | application_remotingname: firefox 04:42:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:42:34 INFO - mozversion INFO | application_vendor: Mozilla 04:42:34 INFO - mozversion INFO | application_version: 46.0a1 04:42:34 INFO - mozversion INFO | platform_buildid: 20151215031712 04:42:34 INFO - mozversion INFO | platform_changeset: 749f9328dd769409fd4c7e105202829e239910ac 04:42:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:42:34 INFO - mozversion INFO | platform_version: 46.0a1 04:42:34 INFO - 2015-12-15 04:42:34,960 DEBUG : using testdate: 1450183354 04:42:34 INFO - 2015-12-15 04:42:34,960 DEBUG : actual date: 1450183354 04:42:35 INFO - 2015-12-15 04:42:35,012 INFO : starting webserver on 'localhost:41566' 04:42:35 INFO - 2015-12-15 04:42:35,013 INFO : Starting test suite talos-linux64-ix-045.e 04:42:35 INFO - 2015-12-15 04:42:35,013 INFO : Starting test dromaeo_css 04:42:35 INFO - 2015-12-15 04:42:35,013 DEBUG : operating with platform_type : linux_ 04:42:35 INFO - 2015-12-15 04:42:35,014 INFO : Initialising browser for dromaeo_css test... 04:42:35 INFO - 2015-12-15 04:42:35,036 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQucNnh/profile http://localhost:41566/getInfo.html 04:42:41 INFO - 2015-12-15 04:42:41,295 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:42:41 INFO - 2015-12-15 04:42:41,295 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:42:41 INFO - 2015-12-15 04:42:41,295 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:42:41 INFO - 2015-12-15 04:42:41,295 DEBUG : BROWSER_OUTPUT: __metrics 04:42:42 INFO - 2015-12-15 04:42:42,591 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:42:43 INFO - 2015-12-15 04:42:43,505 INFO : Browser initialized. 04:42:43 INFO - 2015-12-15 04:42:43,506 INFO : Running cycle 1/1 for dromaeo_css test... 04:42:43 INFO - 2015-12-15 04:42:43,506 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQucNnh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:42:55 INFO - 2015-12-15 04:42:55,570 DEBUG : BROWSER_OUTPUT: RSS: Main: 143167488 04:42:55 INFO - 2015-12-15 04:42:55,570 DEBUG : BROWSER_OUTPUT: 04:43:45 INFO - 2015-12-15 04:43:45,193 DEBUG : BROWSER_OUTPUT: 1450183425192 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:45:57 INFO - 2015-12-15 04:45:57,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41566/tests/dromaeo/cssquery-ext.html) 04:45:58 INFO - 2015-12-15 04:45:58,139 DEBUG : BROWSER_OUTPUT: RSS: Main: 146907136 04:45:58 INFO - 2015-12-15 04:45:58,139 DEBUG : BROWSER_OUTPUT: 04:49:00 INFO - 2015-12-15 04:49:00,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-ext.html (next: http://localhost:41566/tests/dromaeo/cssquery-jquery.html) 04:49:00 INFO - 2015-12-15 04:49:00,737 DEBUG : BROWSER_OUTPUT: RSS: Main: 145129472 04:49:00 INFO - 2015-12-15 04:49:00,737 DEBUG : BROWSER_OUTPUT: 04:52:02 INFO - 2015-12-15 04:52:02,535 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41566/tests/dromaeo/cssquery-mootools.html) 04:52:03 INFO - 2015-12-15 04:52:03,142 DEBUG : BROWSER_OUTPUT: RSS: Main: 145375232 04:52:03 INFO - 2015-12-15 04:52:03,142 DEBUG : BROWSER_OUTPUT: 04:55:04 INFO - 2015-12-15 04:55:04,870 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41566/tests/dromaeo/cssquery-prototype.html) 04:55:05 INFO - 2015-12-15 04:55:05,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 143216640 04:55:05 INFO - 2015-12-15 04:55:05,471 DEBUG : BROWSER_OUTPUT: 04:58:08 INFO - 2015-12-15 04:58:08,088 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41566/tests/dromaeo/cssquery-yui.html) 04:58:08 INFO - 2015-12-15 04:58:08,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 145031168 04:58:08 INFO - 2015-12-15 04:58:08,722 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/dromaeo/cssquery-yui.html (next: http://localhost:41566/tests/dromaeo/cssquery-dojo.html) 05:01:11 INFO - 2015-12-15 05:01:11,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 148021248 05:01:11 INFO - 2015-12-15 05:01:11,342 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,345 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:01:11 INFO - 2015-12-15 05:01:11,346 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:01:11 INFO - 2015-12-15 05:01:11,346 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:01:11 INFO - 2015-12-15 05:01:11,346 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:01:11 INFO - 2015-12-15 05:01:11,346 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2656.375751698351;2720.07517444069;2764.626775385322;2779.1245757586353;2780.8331500109994;4522.276435770277;4733.763311834407;4769.5707386335225;4801.423829140499;4815.783289751961;4670.042641258542;4702.435707715071;4729.976350118253;4880.658353915227;4892.853214403564;4291.206126866539;4368.49762277339;4371.388005679191;4424.203643344197;4473.060657261979;4362.498312694049;4406.228909940747;4501.819927202924;4517.909641807162;4562.908741825178;4238.809253583596;4324.740515569076;4362.323839804835;4402.955970440286;4412.3160910059;4386.078923426085;4390.363397307405;4399.450068741407;4404.14119246747;4404.515503294635;4389.934150987738;4393.165298593257;4398.164348773755;4431.069475410168;4437.822487100512;4525.343825145368;4684.812607495696;4720.43354797423;4736.668433209677;4853.635977301681;5098.337216161875;5140.511651393112;5158.071547121516;5182.948170518284;5219.843404697827;5150.459201783796;5157.355330583677;5173.3533308336455;5195.480451954812;5198.558122559587;4665.043666048452;4710;4745.145873742759;4798.0643774464;4836.516348365135;5208.322918020633;5282.841514754564;5292.947070529322;5293.311869456946;5330.7068111253475;4844.733539655353;5069.7718602662235;5093.363329583802;5094.694318340911;5104.464031276662;5041.521055499722;5083.339165908482;5115.769790359443;5119.0529751996;5170.45710200431;5082.288479612857;5103.566196873233;5119.23736054;5124.385073791169;5155.510226528474;4860.198067318924;5009.474005229471;5064.949350506447;5089.338386009795;5095.541401273903;4121.216968775923;4184.707070505096;4190.685698572619;4213.41012258278;4225.767582782915;3730.552333719971;3731.067233191702;3737.850485980585;3753.5683396409217;3762.0406796266775;4271.487421509439;4325.178216138924;4379.430674012358;4423.712458690175;4432.202203603318;5162.8967420652125;5177.094008548489;5178.326817513775;5191.870203244874;5223.2948551945765;4374.671899607542;4414.161309351213;4432.756198409118;4443.422355093881;4444.022315090675;4358.455193100863;4474.418325617714;4476.955230447719;4539.477960034572;4581.106684196617;4820.758962051954;4839.032193561302;4844.8304309349705;4845.5881250094135;4865.343178670976;5042.117092478453;5075.086484432837;5085.64400491962;5120.4111527174855;5158.535731784157;4692.906141877212;4813.302071199586;4856.344393506832;4862.440819305825;4953.72754498506;5043.344365232496;5114.974425127851;5131.640785145004;5140.43455219918;5185.351831021122;4946.604271658331;5208.661437006583;5211.765470553758;5216.217567364925;5226.686398816083;4910.9263361050325;4928.975355123202;4940.456549779449;5000.424951130521;5020.347354843847;4983;4995;4999.825006124768;5000.14997450427;5007.524285192901;4611.262198048296;4783.402074740658;4911.140550403737;4918.95081049185;4923.5814955729165;4973.950260497493;5047.470015648304;5081.263216833612;5086.821961231339;5119.46245644202;4925.359703238556;4972.676776009548;4985.027919555652;4991.775370108427;5010.073136469765 05:01:11 INFO - 2015-12-15 05:01:11,350 DEBUG : BROWSER_OUTPUT: |1;ext.html;560.5838834622774;567.3078843810549;574.571943901793;588.7027051339073;594.7650677982195;24686.506269874593;35139.47290790637;35585.110372240706;35926.99999999997;35935.281294374094;37999;38356;38524.80737596315;38535.42196867049;38584.42123368152;8001.279884810366;8115.14790946951;8142.715004974827;8155.469894456842;8161.959190204041;7849.764507064797;7909.920900790976;7913.841723165533;7918;7922.920770792305;7982.082060563022;8013.11855695873;8043;8065.6773729051065;8068.354531637455;7857.567833769141;7901.683932642687;7922.722704705337;7935.920640793576;7966.721164759206;7849.411294152905;7975.082865470429;7992.160823113606;8018.398620103516;8023.4784738991775;35493.29013419743;36370.27259454796;36422;36474.817625911965;36493.175341233095;3050.6339239291206;3061.3265081681993;3176.2694580246443;3196.5844440222854;3537.593176784677;27405.314867128283;27488;27801.165965021082;27976.440471190665;28376.432471350457;3259.103746469716;3303.9504407433906;3430.8627654893776;3492.3189977954307;3559.110222444389;218252;221481.14075436964;223689.88155059284;224036;224378;51964.22053669198;53774.35369286169;56769.71615141898;57243;57502;68340.65829670819;69269.65365173142;69401;69755.65122174357;69799.3020069803;14279;14449.927750361181;14507.419703211754;14514.854851451562;14537.200453974921;11314.717132071764;11325.71685707864;11490.482928268248;11505.654830355103;11508.136889733303;19259.61480770377;19796.307129250406;19801.405957821287;19878.403647890587;19944;7624.42816788743;7878.881816772753;7924.247196516321;7946.841063178704;7994.480358776661;824.2169938558394;853.0829358439702;879.8913369154783;924.7040946896929;977.379364103785;26472.470550589267;26643.600345994826;26736.331591710365;26905.730942690323;27081;995.8804943406838;1010.6109147978082;1022.6829682798357;1059.046857827961;1108.1965574958251;13072;13229.470821167046;13237.338133093499;13250.403731832092;13276;306627;312140;312222;312791;312798;47779.76110119427;47825;48081.51918480841;48134.759326203144;48335.758321208166;1236.5857437758273;1240.231056744824;1244.3778110944527;1247.457356050121;1248.9000879929583;23851.642225366286;24506.14228502065;24511;24663;24824.875875620506;35782.28435431225;36053;36100.63899361078;36260.81869590635;36744.81627591845;238082;238558;238890;239383;240406;29188.416231675674;42171.94570135771;42790.57209427866;42946.14107717891;43064.35403469013;45657.543424566655;46882;47286.290705638756;47726.76136619295;48505.5149448501;33057.33885322328;33150.336993260484;33257.501137482475;33718.325633487686;33737.325253495284;32648.45540470982;32727.34545309033;33038.83480582582;33116.337673245915;33219;32873.506897397034;33068.1732956678;33281.66718332786;33372.332553348315;33471;27984.160475185778;28340.149795506168;29536.261593460335;29870.551941721325;29976.700232998264;23461.296161115195;24924.875375623007;25624.615630765926;27294;27810.86094569514 05:01:11 INFO - 2015-12-15 05:01:11,351 DEBUG : BROWSER_OUTPUT: |2;jquery.html;323.409777156689;324.44519871020555;328.17955112219454;329.04248636467884;332.79699383376123;15211.163386013779;15705.921470392646;15745.60635984101;16085.678286434264;16773.161341932886;14965;15108.320125594348;15166.772498412533;15191.392344306214;15209.77185342218;4566.794494247759;4904.975475122637;4939.506049395068;4943.382077240345;4959.231319145538;4923.630727695438;4929.334539837112;4936.35827342445;4952.554270115689;4957.058158949806;3406.4549672052476;3516.964830351702;4857.587105096071;4931;5056.8482945511505;5089.847304580869;5146.974265128651;5150.4334523202415;5150.768215430315;5158.922616160742;4873.585745211662;4949.257611358289;4988.077205716918;4998.075356059105;5025.14572522672;11572.189946703735;11778;11825;12508.874911250861;19392.80607193924;1846.759921210234;1847.8521718262507;1900.4203717866046;1976.2589029114083;2015.8084981926693;9332.300077494216;10530.736731581695;11456.369899655516;12979.351032448434;13104.410301536454;1738.6261953680018;1772.7518147459366;1797.739327797462;1827.0773259503999;1849.5468610190455;55284.44715552833;56184;56646.71676641631;57023.14465283024;58153;9126.087391260953;9486.90513094874;9811.607535698635;9856.112949834549;9894.625289930365;12312.13815032957;12325.322107284186;12339.876601234053;12358.938205308916;12373.195742276903;6929.930700693029;7162;7293.416526677853;7350.52221605594;7374.373178279906;7438.107427108782;7454.291842274975;7477.775666729898;7481.177070522238;7515.849683006419;3956.0307724607655;3963;3965.107850733562;3970.2456533258587;4129.938050929239;1704.6846333428355;1717.4145032762553;1719.8710096742543;1728.4209789720333;1751.1331890714177;378.04543527593046;380.33631313358364;801.1147681811631;904.7738065483629;1021.269792098653;9484.952575237217;9687.757806054906;9945.353552019096;10062.094411503;10149.797004060025;404.97367671101284;406.8189655603287;409.63747083831026;419.34372706713896;420.1324265391956;5467.125259958388;5529.640573362719;5594.1888426177975;5628.183913332626;5656.688882111441;472238;474957;480968;482078;482313;72906.27093728996;74734.25265747381;76588.23411765923;76826;77420;437.8379999400179;438.97585632789867;442.1245933052574;442.1885839484582;445.1608717567409;9062.728118156467;9201.401908875901;9461.905380946102;9497.477638729937;9528.809423811623;30593.847030764704;31411;31454.213644659067;32283.51574726428;32521.674783252813;36418.45372319364;36997.81501092477;38402.61597383991;38607.22785544329;38684.80657596694;8391.916080839113;8622.82754344922;8638;8647.394682372174;8890.911090889009;15422.845771542141;16396;16643.750343745094;16669.99980001204;17024;14078.225697586731;14098.154110753389;14179.574612761633;14196.716065678422;14255.857441425867;12759.170653907466;13501;13521;13533.458661653423;13608.795868061788;13529.526466573623;13842.238676872466;14142.858571414567;14166.150030998173;15000.774988375402;8671.176238257356;8781.041069723058;8863.202311791969;8874.467531948103;8962.910370896208;9347.953260233928;9367.531623418938;12643.620691379274;13208.537701180412;13838.169709817443 05:01:11 INFO - 2015-12-15 05:01:11,353 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1487.8586534279252;1698.4225363376454;1728.507375398011;1731.4805558332505;1775.3519965212693;6581.045748366484;6715.462762978962;6842.931570684292;6992.265812089728;7006.0892084029065;7204.747833825817;7264.600446975414;7303.269673032694;7315.1953285138725;7320.377767889736;2070.9792902070976;2116.7777383374755;2121.66053431451;2125.2880285104507;2132.9893350533225;2091.121728873869;2110.6939493773393;2133.040131940625;2150.2474134053036;2150.827933765303;1975.5752513209657;2011.8289945354663;2014.3252010576475;2098.9370318890383;2117.3436234767273;2034.430359499335;2059.3718915730697;2070.4616799632054;2071.4717746974575;2077.511784730587;2076.532780124475;2137.262644387684;2138.6257404954226;2140.090461553849;2141.593097311506;6751.223609284946;6827.897581536281;6833;6841.760538381182;6847.349501797321;4221;4280.743155410685;4308.935365969482;4312.611864932172;4313.28830742927;2771.514984877638;2808.578713193017;2839.6592408911065;2860.442213768316;2864.2266588021325;871.3856731004627;873.1661263493348;894.7226359828474;895.4269267668698;895.6865097215922;9281;9355.906440935572;9401.294902882242;9436.433813971184;9452.480113593749;3731.6828069613844;3787.905302367463;3846.769193848378;3920.627540383659;3941.4481972523868;7866.685332586632;7871.330936870316;7880.369570434352;7882.132965373804;7885.44801863864;4266.232078225949;4268.167707297047;4292.699511034198;4297.892552686145;4314.201872653569;3938.232044751246;3959.425883246971;3960.6237407446247;3971.781552014667;3991.9002024949027;3102.6276846778533;3135.2005238663996;3145.858436370369;3155.3058327167987;3160.004598551449;932.654917680449;948.4025064209642;956.3879117364892;966.5167416291854;968.8837339519162;864.3690106222367;864.4337958637103;872.520113937332;987.5654711926729;1166.2710805746408;6490.967545162339;6494.480441564663;6515.348465153542;6535.281119076898;6572.211334639874;951.6003278622857;1020.0921180149672;1076.047697787448;1088.9074428673493;1097.2154909239932;5258.1849813279005;5319.840404787863;5325.840224793263;5355.303810504687;5358.089124848785;8397.70608028716;8499.872501912476;8575.656973721107;8645.654173833102;8653.350998674998;8388.664453421918;8422.915770842335;8443.029051659016;8496.957515212385;8571.142885711379;6358.459530939829;6541.34586541342;6560.671966401661;6561.835954101186;6606.570572912745;6670.799876003728;6677.632730199885;6689.899651505134;6731.730730770714;6769.1876974763345;6879.105716256855;6892.827679308058;6900.447964162955;7007.334303241184;7021.262767409348;7257.854842903007;7571.772846814604;7686.538807671557;7689.231076892266;7781.610919454118;6232.532560057924;6277.937220627735;6295.96852015737;6304.621722696469;6350.872982540416;6627.668616569056;6694.966525167343;6764.594124352555;6783.898241526283;6832.59004459734;6292.874142517216;6303.810885673437;6338.8732225354315;6408.359164083555;6638.137042184485;5756.625819321731;6198.752049917953;6215.65813880241;6301.117843501822;6302.180716506825;6130.172426722335;6165.10605962124;6165.321814600301;6175.783847565313;6177.2587289525545;5732.3121225453215;5786.0453025252045;5791.189233507396;5810.128480727924;5828.300603927556;5842.737076831638;5862.296524417097;5865.706714664165;5889.764409423575;5934.7922822701 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: |4;prototype.html;299.9715027072428;304.79730978899033;309.3688874695621;310.8570057773424;312.20232306457;5504.3394792624895;5527.170924361338;5590.217369568259;5667.4049224831415;5715.857103572413;5530.364008139065;5563.972180139093;5566.832995010146;5594.49649531542;5601.80393686221;4114.691398145136;4250.149970005996;4260.850870219528;4269.786510674461;4277.786110694468;4226.830926762926;4232.513260974981;4234.06850492891;4249.277622804116;4261.339492378686;4186.958130418688;4197.097624010837;4197.9370309445385;4202.201581699467;4206.348016057501;4192.895177620553;4206;4208.032152604887;4218.261804184286;4219.599138081878;3551.0944709099012;3630.5824830144606;3631.2918980798754;3637.6907962823193;3638.854445822164;5275.366955965309;5372.086745253317;5397.082495975694;5397.163439666858;5428.891422171574;4836.564709176156;4846.079244943485;4851.8301859435105;4855.635827312965;4856.465788763233;5873.823785286449;5879.8530036749;5886.558508111909;5887.970560147215;5891.587588868781;3851.2297540492013;3932.8033598320258;3953.525576930758;3955.149642826806;4013.5585085640555;6120.693965301762;6168.784092556783;6176.227971503562;6182.629042257479;6187.504999600022;4099.631033207026;4120.299549076664;4126.484189476316;4128.298189307825;4136.627703506639;4169.499660040815;4169.854055108057;4171.520275168334;4175.081482073939;4183.707140500197;4742.549457801503;4831.637627177975;4832.879178020507;4837.661363704507;4847.975760121177;5887.381824908409;5890.2342695449315;5899.587028907935;5901.3508514063415;5903.321118071477;1455.7015811758565;1465.3991863335982;1468.1631470062175;1469.5150600301874;1471.1026273972868;972.8103019911191;973.352720440913;982.577491678585;998.0568362897138;998.7902540466438;5068.923966140511;5069.594432445396;5074.340335756402;5084.923726144111;5088.363954505687;6061.727222275008;6112.786052488231;6122.295935967331;6128.693565321716;6131.877362452726;5017.322661440732;5060.3168572242275;5065.949340506621;5073.492650734971;5092.643514953992;3209.422303985305;3276.4430046892085;3291.4240007998505;3292.983535082309;3295.7476159059406;5611.186377975171;5661.830145095653;5702;5711.828645140569;5804.04233301509;6337.2395312562785;6338.429541341209;6339.144215530937;6339.714712837934;6342;5766.423357664284;5774.422557744192;5778.16216648592;5779.595428319977;5780.768769249183;5993.340732519332;6137.140800287874;6172.567920245539;6173.104899789595;6176.413240742122;6042.818715438544;6074.210352654127;6078.422549857756;6079.696015199311;6079.908801367895;5808.680522571299;5822.796202132905;5832.795852145155;5833.766649333979;5842.474177324061;4045.332520134145;4052.574479679591;4061.614146656063;4072.490938632671;4081.2245673321972;6053.788117415869;6150.630962142107;6183.443490085914;6189.164462797465;6217.409346112112;5913.142594323885;5925.259342582177;5927.407259274038;5931.021381472009;5941.257342832146;5924;5929.347771745191;5936.22829032223;5938.881222375615;5939.495142912728;5565.193047008081;5765.106408506688;5801.593888427769;5807.651540907559;5811.041178205549;5972.104184372379;5984.58107932458;5985.6408615483215;5987.13186587951;5999.520038397005;5833.183354330482;5840.737166827418;5842.795502157404;5842.824715258549;5844.707764611838 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: |5;yui.html;697.3688811625477;730.6931088942647;733.9889901651476;736.6206403702095;785.387397829693;10491.16070714343;10763;10818;10833.187510936672;10838.40388778617;11841.230320029214;11972.401379931012;11976.401179941011;11976.580819671313;12021.639350819467;625.6621424430801;638.0493065332668;793.6904607203195;795.8090058385975;807.8101646113158;814.8533264012505;816.7549735079482;818.9836203275931;820.819419727659;830.6096134816656;848.8344772769312;849.0745087854237;849.524266410812;850.9106543812903;855.3072011670536;842.9114942930996;845.1421806866036;845.6955496021427;848.2789628815519;848.406115718993;631.0817760159003;631.5105793010454;632.0140580694086;632.032989526026;632.1907957814005;10931;10968.890311096866;11068.28056176346;11079;11082.168837337233;11465.656030319104;12008.339541325224;12065.638030859089;12160.695982600506;12166.635000949986;9561.808763824754;9669.758256043584;9716.31985760997;10204.540795664212;10242.334248273839;2965.7182567656255;2975.866086026134;3040.923976900573;3091.644460886982;3284.5894263217097;38467.80766096152;38987.22025559501;40325.39511907324;41072.178556428706;41101;9430.622775089058;9553.092456216647;9572.090651388107;9853.556589953469;9866.80266394668;16527.50417487477;16575.502734917973;16618.75071873923;16684.58288542796;16703.33186672544;6472.967635161794;6511.2512061113575;6517.543771936013;6568.86862262752;6708.396244338033;3658.908527286839;3692.464592634053;3702.629737026276;3770.2271034437877;3780.7920564368674;2618.5548456762394;2661.773749231298;2666.8399896006304;2667.6265322854815;2670.7062223155435;480.5074798331738;484.2711718863089;495.37582645865746;502.70089296868304;504.70979186966906;506.35439814236406;605.5276884030463;611.4710775179437;618.3723520626568;618.8143556932902;7893.921060789434;7984.880226796487;8006.839863202703;8029.780369772925;8071;464.13670572734685;610.2189197826789;625.6965371794676;629.1820633176852;641.2113100885937;3494.947575786365;3533.5406397168204;3584.910377240538;3622.3117607654463;3652.726045546595;50278;50360.496395036316;50524.24213636798;50643.493565064615;50911.23633145506;45654.77172614116;45954.770226148656;46360.5363946363;46680.7665961668;47047.52952470432;41301.380479292835;42019.57980420157;42092.579074208865;42191;43174.56825431768;7689.807754805951;7697.538147711155;7736.961315193388;7921.485103468256;8230.753077407688;31897.202569935936;32373;33427.33145337031;34029.65970340364;34153.316933661685;38411.2317753649;38774.80612596919;39050;39220.215595688496;39594.2081158381;9371.906280937103;9458.905410945803;9518.381305215138;9607;10007.549660265451;10850.511726972147;11210.719732006764;11316;11373.54505819758;11536.769264614828;10505.212109092;10515.895830937641;10621.362718236931;10815.35107893529;11182.496787644413;10383.94808025955;10438;10701.304415212986;10703.19726020536;10932.672019839418;9924.652637157666;10268.229882758973;10747.462626868783;10808.513616887416;11018.39398833072;9044.186023257938;9101.726948191565;9159.771005724644;9176.58705358247;9571.712848614552;8779.195785986003;8843.07147749477;8878.334124940526;8891.19979201875;9116.997130315793 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,321 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: __startTimestamp1450184471343__endTimestamp 05:01:11 INFO - 2015-12-15 05:01:11,354 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4724.01 Median:4856.34 stddev:505.03 (10.4%) stddev-sans-first:479.82 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: Values: 2656.4 2720.1 2764.6 2779.1 2780.8 4522.3 4733.8 4769.6 4801.4 4815.8 4670.0 4702.4 4730.0 4880.7 4892.9 4291.2 4368.5 4371.4 4424.2 4473.1 4362.5 4406.2 4501.8 4517.9 4562.9 4238.8 4324.7 4362.3 4403.0 4412.3 4386.1 4390.4 4399.5 4404.1 4404.5 4389.9 4393.2 4398.2 4431.1 4437.8 4525.3 4684.8 4720.4 4736.7 4853.6 5098.3 5140.5 5158.1 5182.9 5219.8 5150.5 5157.4 5173.4 5195.5 5198.6 4665.0 4710.0 4745.1 4798.1 4836.5 5208.3 5282.8 5292.9 5293.3 5330.7 4844.7 5069.8 5093.4 5094.7 5104.5 5041.5 5083.3 5115.8 5119.1 5170.5 5082.3 5103.6 5119.2 5124.4 5155.5 4860.2 5009.5 5064.9 5089.3 5095.5 4121.2 4184.7 4190.7 4213.4 4225.8 3730.6 3731.1 3737.9 3753.6 3762.0 4271.5 4325.2 4379.4 4423.7 4432.2 5162.9 5177.1 5178.3 5191.9 5223.3 4374.7 4414.2 4432.8 4443.4 4444.0 4358.5 4474.4 4477.0 4539.5 4581.1 4820.8 4839.0 4844.8 4845.6 4865.3 5042.1 5075.1 5085.6 5120.4 5158.5 4692.9 4813.3 4856.3 4862.4 4953.7 5043.3 5115.0 5131.6 5140.4 5185.4 4946.6 5208.7 5211.8 5216.2 5226.7 4910.9 4929.0 4940.5 5000.4 5020.3 4983.0 4995.0 4999.8 5000.1 5007.5 4611.3 4783.4 4911.1 4919.0 4923.6 4974.0 5047.5 5081.3 5086.8 5119.5 4925.4 4972.7 4985.0 4991.8 5010.1 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42434.90 Median:33727.83 stddev:68138.52 (202.0%) stddev-sans-first:68257.13 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: Values: 560.6 567.3 574.6 588.7 594.8 24686.5 35139.5 35585.1 35927.0 35935.3 37999.0 38356.0 38524.8 38535.4 38584.4 8001.3 8115.1 8142.7 8155.5 8162.0 7849.8 7909.9 7913.8 7918.0 7922.9 7982.1 8013.1 8043.0 8065.7 8068.4 7857.6 7901.7 7922.7 7935.9 7966.7 7849.4 7975.1 7992.2 8018.4 8023.5 35493.3 36370.3 36422.0 36474.8 36493.2 3050.6 3061.3 3176.3 3196.6 3537.6 27405.3 27488.0 27801.2 27976.4 28376.4 3259.1 3304.0 3430.9 3492.3 3559.1 218252.0 221481.1 223689.9 224036.0 224378.0 51964.2 53774.4 56769.7 57243.0 57502.0 68340.7 69269.7 69401.0 69755.7 69799.3 14279.0 14449.9 14507.4 14514.9 14537.2 11314.7 11325.7 11490.5 11505.7 11508.1 19259.6 19796.3 19801.4 19878.4 19944.0 7624.4 7878.9 7924.2 7946.8 7994.5 824.2 853.1 879.9 924.7 977.4 26472.5 26643.6 26736.3 26905.7 27081.0 995.9 1010.6 1022.7 1059.0 1108.2 13072.0 13229.5 13237.3 13250.4 13276.0 306627.0 312140.0 312222.0 312791.0 312798.0 47779.8 47825.0 48081.5 48134.8 48335.8 1236.6 1240.2 1244.4 1247.5 1248.9 23851.6 24506.1 24511.0 24663.0 24824.9 35782.3 36053.0 36100.6 36260.8 36744.8 238082.0 238558.0 238890.0 239383.0 240406.0 29188.4 42171.9 42790.6 42946.1 43064.4 45657.5 46882.0 47286.3 47726.8 48505.5 33057.3 33150.3 33257.5 33718.3 33737.3 32648.5 32727.3 33038.8 33116.3 33219.0 32873.5 33068.2 33281.7 33372.3 33471.0 27984.2 28340.1 29536.3 29870.6 29976.7 23461.3 24924.9 25624.6 27294.0 27810.9 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,356 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25571.45 Median:2269.79 stddev:78300.61 (3449.7%) stddev-sans-first:78497.32 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: Values: 323.4 324.4 328.2 329.0 332.8 15211.2 15705.9 15745.6 16085.7 16773.2 14965.0 15108.3 15166.8 15191.4 15209.8 4566.8 4905.0 4939.5 4943.4 4959.2 4923.6 4929.3 4936.4 4952.6 4957.1 3406.5 3517.0 4857.6 4931.0 5056.8 5089.8 5147.0 5150.4 5150.8 5158.9 4873.6 4949.3 4988.1 4998.1 5025.1 11572.2 11778.0 11825.0 12508.9 19392.8 1846.8 1847.9 1900.4 1976.3 2015.8 9332.3 10530.7 11456.4 12979.4 13104.4 1738.6 1772.8 1797.7 1827.1 1849.5 55284.4 56184.0 56646.7 57023.1 58153.0 9126.1 9486.9 9811.6 9856.1 9894.6 12312.1 12325.3 12339.9 12358.9 12373.2 6929.9 7162.0 7293.4 7350.5 7374.4 7438.1 7454.3 7477.8 7481.2 7515.8 3956.0 3963.0 3965.1 3970.2 4129.9 1704.7 1717.4 1719.9 1728.4 1751.1 378.0 380.3 801.1 904.8 1021.3 9485.0 9687.8 9945.4 10062.1 10149.8 405.0 406.8 409.6 419.3 420.1 5467.1 5529.6 5594.2 5628.2 5656.7 472238.0 474957.0 480968.0 482078.0 482313.0 72906.3 74734.3 76588.2 76826.0 77420.0 437.8 439.0 442.1 442.2 445.2 9062.7 9201.4 9461.9 9497.5 9528.8 30593.8 31411.0 31454.2 32283.5 32521.7 36418.5 36997.8 38402.6 38607.2 38684.8 8391.9 8622.8 8638.0 8647.4 8890.9 15422.8 16396.0 16643.8 16670.0 17024.0 14078.2 14098.2 14179.6 14196.7 14255.9 12759.2 13501.0 13521.0 13533.5 13608.8 13529.5 13842.2 14142.9 14166.2 15000.8 8671.2 8781.0 8863.2 8874.5 8962.9 9348.0 9367.5 12643.6 13208.5 13838.2 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4905.38 Median:6294.42 stddev:2495.27 (39.6%) stddev-sans-first:2489.05 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: Values: 1487.9 1698.4 1728.5 1731.5 1775.4 6581.0 6715.5 6842.9 6992.3 7006.1 7204.7 7264.6 7303.3 7315.2 7320.4 2071.0 2116.8 2121.7 2125.3 2133.0 2091.1 2110.7 2133.0 2150.2 2150.8 1975.6 2011.8 2014.3 2098.9 2117.3 2034.4 2059.4 2070.5 2071.5 2077.5 2076.5 2137.3 2138.6 2140.1 2141.6 6751.2 6827.9 6833.0 6841.8 6847.3 4221.0 4280.7 4308.9 4312.6 4313.3 2771.5 2808.6 2839.7 2860.4 2864.2 871.4 873.2 894.7 895.4 895.7 9281.0 9355.9 9401.3 9436.4 9452.5 3731.7 3787.9 3846.8 3920.6 3941.4 7866.7 7871.3 7880.4 7882.1 7885.4 4266.2 4268.2 4292.7 4297.9 4314.2 3938.2 3959.4 3960.6 3971.8 3991.9 3102.6 3135.2 3145.9 3155.3 3160.0 932.7 948.4 956.4 966.5 968.9 864.4 864.4 872.5 987.6 1166.3 6491.0 6494.5 6515.3 6535.3 6572.2 951.6 1020.1 1076.0 1088.9 1097.2 5258.2 5319.8 5325.8 5355.3 5358.1 8397.7 8499.9 8575.7 8645.7 8653.4 8388.7 8422.9 8443.0 8497.0 8571.1 6358.5 6541.3 6560.7 6561.8 6606.6 6670.8 6677.6 6689.9 6731.7 6769.2 6879.1 6892.8 6900.4 7007.3 7021.3 7257.9 7571.8 7686.5 7689.2 7781.6 6232.5 6277.9 6296.0 6304.6 6350.9 6627.7 6695.0 6764.6 6783.9 6832.6 6292.9 6303.8 6338.9 6408.4 6638.1 5756.6 6198.8 6215.7 6301.1 6302.2 6130.2 6165.1 6165.3 6175.8 6177.3 5732.3 5786.0 5791.2 5810.1 5828.3 5842.7 5862.3 5865.7 5889.8 5934.8 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4852.35 Median:5578.53 stddev:1467.05 (26.3%) stddev-sans-first:1430.82 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: Values: 300.0 304.8 309.4 310.9 312.2 5504.3 5527.2 5590.2 5667.4 5715.9 5530.4 5564.0 5566.8 5594.5 5601.8 4114.7 4250.1 4260.9 4269.8 4277.8 4226.8 4232.5 4234.1 4249.3 4261.3 4187.0 4197.1 4197.9 4202.2 4206.3 4192.9 4206.0 4208.0 4218.3 4219.6 3551.1 3630.6 3631.3 3637.7 3638.9 5275.4 5372.1 5397.1 5397.2 5428.9 4836.6 4846.1 4851.8 4855.6 4856.5 5873.8 5879.9 5886.6 5888.0 5891.6 3851.2 3932.8 3953.5 3955.1 4013.6 6120.7 6168.8 6176.2 6182.6 6187.5 4099.6 4120.3 4126.5 4128.3 4136.6 4169.5 4169.9 4171.5 4175.1 4183.7 4742.5 4831.6 4832.9 4837.7 4848.0 5887.4 5890.2 5899.6 5901.4 5903.3 1455.7 1465.4 1468.2 1469.5 1471.1 972.8 973.4 982.6 998.1 998.8 5068.9 5069.6 5074.3 5084.9 5088.4 6061.7 6112.8 6122.3 6128.7 6131.9 5017.3 5060.3 5065.9 5073.5 5092.6 3209.4 3276.4 3291.4 3293.0 3295.7 5611.2 5661.8 5702.0 5711.8 5804.0 6337.2 6338.4 6339.1 6339.7 6342.0 5766.4 5774.4 5778.2 5779.6 5780.8 5993.3 6137.1 6172.6 6173.1 6176.4 6042.8 6074.2 6078.4 6079.7 6079.9 5808.7 5822.8 5832.8 5833.8 5842.5 4045.3 4052.6 4061.6 4072.5 4081.2 6053.8 6150.6 6183.4 6189.2 6217.4 5913.1 5925.3 5927.4 5931.0 5941.3 5924.0 5929.3 5936.2 5938.9 5939.5 5565.2 5765.1 5801.6 5807.7 5811.0 5972.1 5984.6 5985.6 5987.1 5999.5 5833.2 5840.7 5842.8 5842.8 5844.7 05:01:11 INFO - 2015-12-15 05:01:11,358 DEBUG : BROWSER_OUTPUT: 05:01:11 INFO - 2015-12-15 05:01:11,359 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12380.21 Median:23572.45 stddev:14098.45 (59.8%) stddev-sans-first:14110.70 05:01:11 INFO - 2015-12-15 05:01:11,360 DEBUG : BROWSER_OUTPUT: Values: 697.4 730.7 734.0 736.6 785.4 10491.2 10763.0 10818.0 10833.2 10838.4 11841.2 11972.4 11976.4 11976.6 12021.6 625.7 638.0 793.7 795.8 807.8 814.9 816.8 819.0 820.8 830.6 848.8 849.1 849.5 850.9 855.3 842.9 845.1 845.7 848.3 848.4 631.1 631.5 632.0 632.0 632.2 10931.0 10968.9 11068.3 11079.0 11082.2 11465.7 12008.3 12065.6 12160.7 12166.6 9561.8 9669.8 9716.3 10204.5 10242.3 2965.7 2975.9 3040.9 3091.6 3284.6 38467.8 38987.2 40325.4 41072.2 41101.0 9430.6 9553.1 9572.1 9853.6 9866.8 16527.5 16575.5 16618.8 16684.6 16703.3 6473.0 6511.3 6517.5 6568.9 6708.4 3658.9 3692.5 3702.6 3770.2 3780.8 2618.6 2661.8 2666.8 2667.6 2670.7 480.5 484.3 495.4 502.7 504.7 506.4 605.5 611.5 618.4 618.8 7893.9 7984.9 8006.8 8029.8 8071.0 464.1 610.2 625.7 629.2 641.2 3494.9 3533.5 3584.9 3622.3 3652.7 50278.0 50360.5 50524.2 50643.5 50911.2 45654.8 45954.8 46360.5 46680.8 47047.5 41301.4 42019.6 42092.6 42191.0 43174.6 7689.8 7697.5 7737.0 7921.5 8230.8 31897.2 32373.0 33427.3 34029.7 34153.3 38411.2 38774.8 39050.0 39220.2 39594.2 9371.9 9458.9 9518.4 9607.0 10007.5 10850.5 11210.7 11316.0 11373.5 11536.8 10505.2 10515.9 10621.4 10815.4 11182.5 10383.9 10438.0 10701.3 10703.2 10932.7 9924.7 10268.2 10747.5 10808.5 11018.4 9044.2 9101.7 9159.8 9176.6 9571.7 8779.2 8843.1 8878.3 8891.2 9117.0 05:01:11 INFO - 2015-12-15 05:01:11,360 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:01:11 INFO - 2015-12-15 05:01:11,360 DEBUG : BROWSER_OUTPUT: 05:01:12 INFO - 2015-12-15 05:01:12,065 INFO : Browser exited with error code: 0 05:01:12 INFO - 2015-12-15 05:01:12,084 INFO : Completed test dromaeo_css (00:18:37) 05:01:12 INFO - 2015-12-15 05:01:12,084 INFO : Starting test kraken 05:01:12 INFO - 2015-12-15 05:01:12,084 DEBUG : operating with platform_type : linux_ 05:01:12 INFO - 2015-12-15 05:01:12,084 INFO : Initialising browser for kraken test... 05:01:12 INFO - 2015-12-15 05:01:12,090 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6bWhzA/profile http://localhost:41566/getInfo.html 05:01:16 INFO - 2015-12-15 05:01:16,611 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:01:16 INFO - 2015-12-15 05:01:16,611 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:01:16 INFO - 2015-12-15 05:01:16,611 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:01:16 INFO - 2015-12-15 05:01:16,611 DEBUG : BROWSER_OUTPUT: __metrics 05:01:16 INFO - 2015-12-15 05:01:16,719 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:01:17 INFO - 2015-12-15 05:01:17,629 INFO : Browser initialized. 05:01:17 INFO - 2015-12-15 05:01:17,630 INFO : Running cycle 1/1 for kraken test... 05:01:17 INFO - 2015-12-15 05:01:17,630 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6bWhzA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:01:29 INFO - 2015-12-15 05:01:29,755 DEBUG : BROWSER_OUTPUT: RSS: Main: 143925248 05:01:29 INFO - 2015-12-15 05:01:29,755 DEBUG : BROWSER_OUTPUT: 05:01:32 INFO - 2015-12-15 05:01:32,351 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-beat-detection) 05:01:32 INFO - 2015-12-15 05:01:32,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 142987264 05:01:32 INFO - 2015-12-15 05:01:32,965 DEBUG : BROWSER_OUTPUT: 05:01:35 INFO - 2015-12-15 05:01:35,764 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-dft) 05:01:36 INFO - 2015-12-15 05:01:36,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 143769600 05:01:36 INFO - 2015-12-15 05:01:36,366 DEBUG : BROWSER_OUTPUT: 05:01:39 INFO - 2015-12-15 05:01:39,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-fft) 05:01:39 INFO - 2015-12-15 05:01:39,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 139157504 05:01:39 INFO - 2015-12-15 05:01:39,857 DEBUG : BROWSER_OUTPUT: 05:01:41 INFO - 2015-12-15 05:01:41,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41566/tests/kraken/driver.html?testName=audio-oscillator) 05:01:42 INFO - 2015-12-15 05:01:42,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 139501568 05:01:42 INFO - 2015-12-15 05:01:42,542 DEBUG : BROWSER_OUTPUT: 05:01:44 INFO - 2015-12-15 05:01:44,869 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:01:45 INFO - 2015-12-15 05:01:45,455 DEBUG : BROWSER_OUTPUT: RSS: Main: 140693504 05:01:45 INFO - 2015-12-15 05:01:45,455 DEBUG : BROWSER_OUTPUT: 05:01:48 INFO - 2015-12-15 05:01:48,554 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41566/tests/kraken/driver.html?testName=imaging-darkroom) 05:01:49 INFO - 2015-12-15 05:01:49,139 DEBUG : BROWSER_OUTPUT: RSS: Main: 139739136 05:01:49 INFO - 2015-12-15 05:01:49,139 DEBUG : BROWSER_OUTPUT: 05:01:52 INFO - 2015-12-15 05:01:52,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41566/tests/kraken/driver.html?testName=imaging-desaturate) 05:01:53 INFO - 2015-12-15 05:01:53,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 138424320 05:01:53 INFO - 2015-12-15 05:01:53,256 DEBUG : BROWSER_OUTPUT: 05:01:56 INFO - 2015-12-15 05:01:56,180 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41566/tests/kraken/driver.html?testName=json-parse-financial) 05:01:56 INFO - 2015-12-15 05:01:56,773 DEBUG : BROWSER_OUTPUT: RSS: Main: 138493952 05:01:56 INFO - 2015-12-15 05:01:56,773 DEBUG : BROWSER_OUTPUT: 05:01:58 INFO - 2015-12-15 05:01:58,511 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:01:59 INFO - 2015-12-15 05:01:59,092 DEBUG : BROWSER_OUTPUT: RSS: Main: 140681216 05:01:59 INFO - 2015-12-15 05:01:59,092 DEBUG : BROWSER_OUTPUT: 05:02:00 INFO - 2015-12-15 05:02:00,969 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:02:01 INFO - 2015-12-15 05:02:01,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 141221888 05:02:01 INFO - 2015-12-15 05:02:01,583 DEBUG : BROWSER_OUTPUT: 05:02:03 INFO - 2015-12-15 05:02:03,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:02:04 INFO - 2015-12-15 05:02:04,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 141410304 05:02:04 INFO - 2015-12-15 05:02:04,322 DEBUG : BROWSER_OUTPUT: 05:02:06 INFO - 2015-12-15 05:02:06,877 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:02:07 INFO - 2015-12-15 05:02:07,470 DEBUG : BROWSER_OUTPUT: RSS: Main: 141725696 05:02:07 INFO - 2015-12-15 05:02:07,470 DEBUG : BROWSER_OUTPUT: 05:02:10 INFO - 2015-12-15 05:02:10,077 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:02:10 INFO - 2015-12-15 05:02:10,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 141291520 05:02:10 INFO - 2015-12-15 05:02:10,667 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41566/tests/kraken/driver.html?testName=ai-astar) 05:02:12 INFO - 2015-12-15 05:02:12,674 DEBUG : BROWSER_OUTPUT: RSS: Main: 142651392 05:02:12 INFO - 2015-12-15 05:02:12,674 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,677 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:02:12 INFO - 2015-12-15 05:02:12,677 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:02:12 INFO - 2015-12-15 05:02:12,678 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:02:12 INFO - 2015-12-15 05:02:12,678 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:02:12 INFO - 2015-12-15 05:02:12,679 DEBUG : BROWSER_OUTPUT: |0;ai-astar;116;108;112;112;115;124;112;112;112;111 05:02:12 INFO - 2015-12-15 05:02:12,679 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;137;153;172;141;151;155;139;171;143 05:02:12 INFO - 2015-12-15 05:02:12,679 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;163;160;163;166;161;162;162;165 05:02:12 INFO - 2015-12-15 05:02:12,679 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;74;75;73;134;74;78;72;72;89 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;126;94;95;96;138;94;96;130 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;114;110;110;110;110;110;111;110;111 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;149;149;147;148;148;147;148;148;148;147 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;98;97;97;96;97;96;97;98;96 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;72;73;72;72;72;72;71;70;69 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;68;68;68;68;69;68;69;69;69 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;86;88;86;96;85;84;86;88;86 05:02:12 INFO - 2015-12-15 05:02:12,680 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;132;121;124;129;127;121;123;126;130;124 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;148;151;147;148;149;149;149;144;139 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;73;71;70;71;70;69;70;71;69 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,777 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:02:12 INFO - 2015-12-15 05:02:12,681 DEBUG : BROWSER_OUTPUT: __startTimestamp1450184532676__endTimestamp 05:02:12 INFO - 2015-12-15 05:02:12,682 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,685 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:02:12 INFO - 2015-12-15 05:02:12,685 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:02:12 INFO - 2015-12-15 05:02:12,685 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,685 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.40 Median:112.00 stddev:4.30 (3.8%) stddev-sans-first:4.46 05:02:12 INFO - 2015-12-15 05:02:12,685 DEBUG : BROWSER_OUTPUT: Values: 116.0 108.0 112.0 112.0 115.0 124.0 112.0 112.0 112.0 111.0 05:02:12 INFO - 2015-12-15 05:02:12,685 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:152.40 Median:154.00 stddev:12.76 (8.3%) stddev-sans-first:13.06 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: Values: 162.0 137.0 153.0 172.0 141.0 151.0 155.0 139.0 171.0 143.0 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.77 (1.1%) stddev-sans-first:1.87 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 163.0 160.0 163.0 166.0 161.0 162.0 162.0 165.0 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.40 Median:74.00 stddev:19.16 (25.9%) stddev-sans-first:20.08 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: Values: 73.0 74.0 75.0 73.0 134.0 74.0 78.0 72.0 72.0 89.0 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:94.50 stddev:17.87 (18.9%) stddev-sans-first:18.44 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 126.0 94.0 95.0 96.0 138.0 94.0 96.0 130.0 05:02:12 INFO - 2015-12-15 05:02:12,686 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.60 Median:110.00 stddev:1.26 (1.1%) stddev-sans-first:1.32 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: Values: 110.0 114.0 110.0 110.0 110.0 110.0 110.0 111.0 110.0 111.0 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: Values: 149.0 149.0 147.0 148.0 148.0 147.0 148.0 148.0 148.0 147.0 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.80 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: Values: 96.0 98.0 97.0 97.0 96.0 97.0 96.0 97.0 98.0 96.0 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,687 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.60 Median:72.00 stddev:1.26 (1.8%) stddev-sans-first:1.24 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 73.0 72.0 72.0 72.0 72.0 71.0 70.0 69.0 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 68.0 68.0 68.0 69.0 68.0 69.0 69.0 69.0 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.00 stddev:3.48 (4.0%) stddev-sans-first:3.53 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: Values: 84.0 86.0 88.0 86.0 96.0 85.0 84.0 86.0 88.0 86.0 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:126.50 stddev:3.77 (3.0%) stddev-sans-first:3.24 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: Values: 132.0 121.0 124.0 129.0 127.0 121.0 123.0 126.0 130.0 124.0 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.30 Median:148.50 stddev:4.24 (2.9%) stddev-sans-first:3.59 05:02:12 INFO - 2015-12-15 05:02:12,688 DEBUG : BROWSER_OUTPUT: Values: 139.0 148.0 151.0 147.0 148.0 149.0 149.0 149.0 144.0 139.0 05:02:12 INFO - 2015-12-15 05:02:12,689 DEBUG : BROWSER_OUTPUT: 05:02:12 INFO - 2015-12-15 05:02:12,689 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:71.00 stddev:1.18 (1.7%) stddev-sans-first:1.24 05:02:12 INFO - 2015-12-15 05:02:12,689 DEBUG : BROWSER_OUTPUT: Values: 71.0 73.0 71.0 70.0 71.0 70.0 69.0 70.0 71.0 69.0 05:02:12 INFO - 2015-12-15 05:02:12,689 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:02:12 INFO - 2015-12-15 05:02:12,689 DEBUG : BROWSER_OUTPUT: 05:02:13 INFO - 2015-12-15 05:02:13,649 INFO : Browser exited with error code: 0 05:02:13 INFO - 2015-12-15 05:02:13,702 INFO : Completed test kraken (00:01:01) 05:02:13 INFO - 2015-12-15 05:02:13,702 INFO : Starting test v8_7 05:02:13 INFO - 2015-12-15 05:02:13,702 DEBUG : operating with platform_type : linux_ 05:02:13 INFO - 2015-12-15 05:02:13,702 INFO : Initialising browser for v8_7 test... 05:02:13 INFO - 2015-12-15 05:02:13,709 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe5RjvL/profile http://localhost:41566/getInfo.html 05:02:18 INFO - 2015-12-15 05:02:18,229 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:02:18 INFO - 2015-12-15 05:02:18,229 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:02:18 INFO - 2015-12-15 05:02:18,229 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:02:18 INFO - 2015-12-15 05:02:18,229 DEBUG : BROWSER_OUTPUT: __metrics 05:02:18 INFO - 2015-12-15 05:02:18,350 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:02:19 INFO - 2015-12-15 05:02:19,233 INFO : Browser initialized. 05:02:19 INFO - 2015-12-15 05:02:19,233 INFO : Running cycle 1/1 for v8_7 test... 05:02:19 INFO - 2015-12-15 05:02:19,234 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe5RjvL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:02:31 INFO - 2015-12-15 05:02:31,283 DEBUG : BROWSER_OUTPUT: RSS: Main: 143065088 05:02:31 INFO - 2015-12-15 05:02:31,283 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,741 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41566/tests/v8_7/run.html (next: http://localhost:41566/tests/v8_7/run.html) 05:02:52 INFO - 2015-12-15 05:02:52,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 137904128 05:02:52 INFO - 2015-12-15 05:02:52,992 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: |0;Richards;166.14055490945339 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.789666473877 05:02:52 INFO - 2015-12-15 05:02:52,993 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5278.9473684210525 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1081.081081081081 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |5;Earley;721.5007215007215 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |6;Boyer;12180.722891566265 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |7;RegExp;26076.923076923078 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |8;Splay;608.6427267194157 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 05:02:52 INFO - 2015-12-15 05:02:52,994 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: __startTimestamp1450184572990__endTimestamp 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.14 Median:166.14 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,995 DEBUG : BROWSER_OUTPUT: Values: 166.1 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.79 Median:192.79 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: Values: 192.8 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: Values: 278.1 05:02:52 INFO - 2015-12-15 05:02:52,996 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5278.95 Median:5278.95 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: Values: 5278.9 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1081.08 Median:1081.08 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: Values: 1081.1 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:721.50 Median:721.50 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: Values: 721.5 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,997 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12180.72 Median:12180.72 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: Values: 12180.7 05:02:52 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26076.92 Median:26076.92 stddev:0.00 (0.0%) 05:02:52 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: Values: 26076.9 05:02:52 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: 05:02:52 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:608.64 Median:608.64 stddev:0.00 (0.0%) 05:02:53 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: Values: 608.6 05:02:53 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: 05:02:53 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 05:02:53 INFO - 2015-12-15 05:02:52,998 DEBUG : BROWSER_OUTPUT: Values: 4840.6 05:02:53 INFO - 2015-12-15 05:02:52,999 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:02:53 INFO - 2015-12-15 05:02:52,999 DEBUG : BROWSER_OUTPUT: 05:02:53 INFO - 2015-12-15 05:02:53,897 INFO : Browser exited with error code: 0 05:02:53 INFO - 2015-12-15 05:02:53,909 INFO : Completed test v8_7 (00:00:40) 05:02:53 INFO - 2015-12-15 05:02:53,967 INFO : Completed test suite (00:20:18) 05:02:53 INFO - 2015-12-15 05:02:53,967 DEBUG : Working with test: dromaeo_css 05:02:53 INFO - 2015-12-15 05:02:53,968 DEBUG : Generating results file: dromaeo_css 05:02:53 INFO - 2015-12-15 05:02:53,969 DEBUG : Working with test: kraken 05:02:53 INFO - 2015-12-15 05:02:53,969 DEBUG : Generating results file: kraken 05:02:53 INFO - 2015-12-15 05:02:53,969 INFO : javascript benchmark 05:02:53 INFO - 2015-12-15 05:02:53,969 DEBUG : Working with test: v8_7 05:02:53 INFO - 2015-12-15 05:02:53,969 DEBUG : Generating results file: v8_7 05:02:53 INFO - 2015-12-15 05:02:53,970 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:02:54 INFO - 2015-12-15 05:02:54,024 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 05:02:54 INFO - 2015-12-15 05:02:54,024 DEBUG : process_Request line: dromaeo_css 6293.79 graph.html#tests=[[72,94,43]] 05:02:54 INFO - 2015-12-15 05:02:54,024 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:02:54 INFO - 2015-12-15 05:02:54,052 DEBUG : process_Request line: kraken 1540.50 graph.html#tests=[[232,94,43]] 05:02:54 INFO - 2015-12-15 05:02:54,052 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:02:54 INFO - 2015-12-15 05:02:54,083 DEBUG : process_Request line: v8_7 21180.86 graph.html#tests=[[230,94,43]] 05:02:54 INFO - 2015-12-15 05:02:54,084 INFO : javascript benchmark 05:02:54 INFO - 2015-12-15 05:02:54,088 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2656.375751698351, 2720.07517444069, 2764.626775385322, 2779.1245757586353, 2780.8331500109994, 4522.276435770277, 4733.763311834407, 4769.5707386335225, 4801.423829140499, 4815.783289751961, 4670.042641258542, 4702.435707715071, 4729.976350118253, 4880.658353915227, 4892.853214403564, 4291.206126866539, 4368.49762277339, 4371.388005679191, 4424.203643344197, 4473.060657261979, 4362.498312694049, 4406.228909940747, 4501.819927202924, 4517.909641807162, 4562.908741825178, 4238.809253583596, 4324.740515569076, 4362.323839804835, 4402.955970440286, 4412.3160910059, 4386.078923426085, 4390.363397307405, 4399.450068741407, 4404.14119246747, 4404.515503294635, 4389.934150987738, 4393.165298593257, 4398.164348773755, 4431.069475410168, 4437.822487100512, 4525.343825145368, 4684.812607495696, 4720.43354797423, 4736.668433209677, 4853.635977301681, 5098.337216161875, 5140.511651393112, 5158.071547121516, 5182.948170518284, 5219.843404697827, 5150.459201783796, 5157.355330583677, 5173.3533308336455, 5195.480451954812, 5198.558122559587, 4665.043666048452, 4710.0, 4745.145873742759, 4798.0643774464, 4836.516348365135, 5208.322918020633, 5282.841514754564, 5292.947070529322, 5293.311869456946, 5330.7068111253475, 4844.733539655353, 5069.7718602662235, 5093.363329583802, 5094.694318340911, 5104.464031276662, 5041.521055499722, 5083.339165908482, 5115.769790359443, 5119.0529751996, 5170.45710200431, 5082.288479612857, 5103.566196873233, 5119.23736054, 5124.385073791169, 5155.510226528474, 4860.198067318924, 5009.474005229471, 5064.949350506447, 5089.338386009795, 5095.541401273903, 4121.216968775923, 4184.707070505096, 4190.685698572619, 4213.41012258278, 4225.767582782915, 3730.552333719971, 3731.067233191702, 3737.850485980585, 3753.5683396409217, 3762.0406796266775, 4271.487421509439, 4325.178216138924, 4379.430674012358, 4423.712458690175, 4432.202203603318, 5162.8967420652125, 5177.094008548489, 5178.326817513775, 5191.870203244874, 5223.2948551945765, 4374.671899607542, 4414.161309351213, 4432.756198409118, 4443.422355093881, 4444.022315090675, 4358.455193100863, 4474.418325617714, 4476.955230447719, 4539.477960034572, 4581.106684196617, 4820.758962051954, 4839.032193561302, 4844.8304309349705, 4845.5881250094135, 4865.343178670976, 5042.117092478453, 5075.086484432837, 5085.64400491962, 5120.4111527174855, 5158.535731784157, 4692.906141877212, 4813.302071199586, 4856.344393506832, 4862.440819305825, 4953.72754498506, 5043.344365232496, 5114.974425127851, 5131.640785145004, 5140.43455219918, 5185.351831021122, 4946.604271658331, 5208.661437006583, 5211.765470553758, 5216.217567364925, 5226.686398816083, 4910.9263361050325, 4928.975355123202, 4940.456549779449, 5000.424951130521, 5020.347354843847, 4983.0, 4995.0, 4999.825006124768, 5000.14997450427, 5007.524285192901, 4611.262198048296, 4783.402074740658, 4911.140550403737, 4918.95081049185, 4923.5814955729165, 4973.950260497493, 5047.470015648304, 5081.263216833612, 5086.821961231339, 5119.46245644202, 4925.359703238556, 4972.676776009548, 4985.027919555652, 4991.775370108427, 5010.073136469765], "unit": "score", "name": "dojo.html", "value": 4692.163845927103}, {"lowerIsBetter": false, "replicates": [560.5838834622774, 567.3078843810549, 574.571943901793, 588.7027051339073, 594.7650677982195, 24686.506269874593, 35139.47290790637, 35585.110372240706, 35926.99999999997, 35935.281294374094, 37999.0, 38356.0, 38524.80737596315, 38535.42196867049, 38584.42123368152, 8001.279884810366, 8115.14790946951, 8142.715004974827, 8155.469894456842, 8161.959190204041, 7849.764507064797, 7909.920900790976, 7913.841723165533, 7918.0, 7922.920770792305, 7982.082060563022, 8013.11855695873, 8043.0, 8065.6773729051065, 8068.354531637455, 7857.567833769141, 7901.683932642687, 7922.722704705337, 7935.920640793576, 7966.721164759206, 7849.411294152905, 7975.082865470429, 7992.160823113606, 8018.398620103516, 8023.4784738991775, 35493.29013419743, 36370.27259454796, 36422.0, 36474.817625911965, 36493.175341233095, 3050.6339239291206, 3061.3265081681993, 3176.2694580246443, 3196.5844440222854, 3537.593176784677, 27405.314867128283, 27488.0, 27801.165965021082, 27976.440471190665, 28376.432471350457, 3259.103746469716, 3303.9504407433906, 3430.8627654893776, 3492.3189977954307, 3559.110222444389, 218252.0, 221481.14075436964, 223689.88155059284, 224036.0, 224378.0, 51964.22053669198, 53774.35369286169, 56769.71615141898, 57243.0, 57502.0, 68340.65829670819, 69269.65365173142, 69401.0, 69755.65122174357, 69799.3020069803, 14279.0, 14449.927750361181, 14507.419703211754, 14514.854851451562, 14537.200453974921, 11314.717132071764, 11325.71685707864, 11490.482928268248, 11505.654830355103, 11508.136889733303, 19259.61480770377, 19796.307129250406, 19801.405957821287, 19878.403647890587, 19944.0, 7624.42816788743, 7878.881816772753, 7924.247196516321, 7946.841063178704, 7994.480358776661, 824.2169938558394, 853.0829358439702, 879.8913369154783, 924.7040946896929, 977.379364103785, 26472.470550589267, 26643.600345994826, 26736.331591710365, 26905.730942690323, 27081.0, 995.8804943406838, 1010.6109147978082, 1022.6829682798357, 1059.046857827961, 1108.1965574958251, 13072.0, 13229.470821167046, 13237.338133093499, 13250.403731832092, 13276.0, 306627.0, 312140.0, 312222.0, 312791.0, 312798.0, 47779.76110119427, 47825.0, 48081.51918480841, 48134.759326203144, 48335.758321208166, 1236.5857437758273, 1240.231056744824, 1244.3778110944527, 1247.457356050121, 1248.9000879929583, 23851.642225366286, 24506.14228502065, 24511.0, 24663.0, 24824.875875620506, 35782.28435431225, 36053.0, 36100.63899361078, 36260.81869590635, 36744.81627591845, 238082.0, 238558.0, 238890.0, 239383.0, 240406.0, 29188.416231675674, 42171.94570135771, 42790.57209427866, 42946.14107717891, 43064.35403469013, 45657.543424566655, 46882.0, 47286.290705638756, 47726.76136619295, 48505.5149448501, 33057.33885322328, 33150.336993260484, 33257.501137482475, 33718.325633487686, 33737.325253495284, 32648.45540470982, 32727.34545309033, 33038.83480582582, 33116.337673245915, 33219.0, 32873.506897397034, 33068.1732956678, 33281.66718332786, 33372.332553348315, 33471.0, 27984.160475185778, 28340.149795506168, 29536.261593460335, 29870.551941721325, 29976.700232998264, 23461.296161115195, 24924.875375623007, 25624.615630765926, 27294.0, 27810.86094569514], "unit": "score", "name": "ext.html", "value": 17326.93440705059}, {"lowerIsBetter": false, "replicates": [323.409777156689, 324.44519871020555, 328.17955112219454, 329.04248636467884, 332.79699383376123, 15211.163386013779, 15705.921470392646, 15745.60635984101, 16085.678286434264, 16773.161341932886, 14965.0, 15108.320125594348, 15166.772498412533, 15191.392344306214, 15209.77185342218, 4566.794494247759, 4904.975475122637, 4939.506049395068, 4943.382077240345, 4959.231319145538, 4923.630727695438, 4929.334539837112, 4936.35827342445, 4952.554270115689, 4957.058158949806, 3406.4549672052476, 3516.964830351702, 4857.587105096071, 4931.0, 5056.8482945511505, 5089.847304580869, 5146.974265128651, 5150.4334523202415, 5150.768215430315, 5158.922616160742, 4873.585745211662, 4949.257611358289, 4988.077205716918, 4998.075356059105, 5025.14572522672, 11572.189946703735, 11778.0, 11825.0, 12508.874911250861, 19392.80607193924, 1846.759921210234, 1847.8521718262507, 1900.4203717866046, 1976.2589029114083, 2015.8084981926693, 9332.300077494216, 10530.736731581695, 11456.369899655516, 12979.351032448434, 13104.410301536454, 1738.6261953680018, 1772.7518147459366, 1797.739327797462, 1827.0773259503999, 1849.5468610190455, 55284.44715552833, 56184.0, 56646.71676641631, 57023.14465283024, 58153.0, 9126.087391260953, 9486.90513094874, 9811.607535698635, 9856.112949834549, 9894.625289930365, 12312.13815032957, 12325.322107284186, 12339.876601234053, 12358.938205308916, 12373.195742276903, 6929.930700693029, 7162.0, 7293.416526677853, 7350.52221605594, 7374.373178279906, 7438.107427108782, 7454.291842274975, 7477.775666729898, 7481.177070522238, 7515.849683006419, 3956.0307724607655, 3963.0, 3965.107850733562, 3970.2456533258587, 4129.938050929239, 1704.6846333428355, 1717.4145032762553, 1719.8710096742543, 1728.4209789720333, 1751.1331890714177, 378.04543527593046, 380.33631313358364, 801.1147681811631, 904.7738065483629, 1021.269792098653, 9484.952575237217, 9687.757806054906, 9945.353552019096, 10062.094411503, 10149.797004060025, 404.97367671101284, 406.8189655603287, 409.63747083831026, 419.34372706713896, 420.1324265391956, 5467.125259958388, 5529.640573362719, 5594.1888426177975, 5628.183913332626, 5656.688882111441, 472238.0, 474957.0, 480968.0, 482078.0, 482313.0, 72906.27093728996, 74734.25265747381, 76588.23411765923, 76826.0, 77420.0, 437.8379999400179, 438.97585632789867, 442.1245933052574, 442.1885839484582, 445.1608717567409, 9062.728118156467, 9201.401908875901, 9461.905380946102, 9497.477638729937, 9528.809423811623, 30593.847030764704, 31411.0, 31454.213644659067, 32283.51574726428, 32521.674783252813, 36418.45372319364, 36997.81501092477, 38402.61597383991, 38607.22785544329, 38684.80657596694, 8391.916080839113, 8622.82754344922, 8638.0, 8647.394682372174, 8890.911090889009, 15422.845771542141, 16396.0, 16643.750343745094, 16669.99980001204, 17024.0, 14078.225697586731, 14098.154110753389, 14179.574612761633, 14196.716065678422, 14255.857441425867, 12759.170653907466, 13501.0, 13521.0, 13533.458661653423, 13608.795868061788, 13529.526466573623, 13842.238676872466, 14142.858571414567, 14166.150030998173, 15000.774988375402, 8671.176238257356, 8781.041069723058, 8863.202311791969, 8874.467531948103, 8962.910370896208, 9347.953260233928, 9367.531623418938, 12643.620691379274, 13208.537701180412, 13838.169709817443], "unit": "score", "name": "jquery.html", "value": 7497.009758771626}, {"lowerIsBetter": false, "replicates": [1487.8586534279252, 1698.4225363376454, 1728.507375398011, 1731.4805558332505, 1775.3519965212693, 6581.045748366484, 6715.462762978962, 6842.931570684292, 6992.265812089728, 7006.0892084029065, 7204.747833825817, 7264.600446975414, 7303.269673032694, 7315.1953285138725, 7320.377767889736, 2070.9792902070976, 2116.7777383374755, 2121.66053431451, 2125.2880285104507, 2132.9893350533225, 2091.121728873869, 2110.6939493773393, 2133.040131940625, 2150.2474134053036, 2150.827933765303, 1975.5752513209657, 2011.8289945354663, 2014.3252010576475, 2098.9370318890383, 2117.3436234767273, 2034.430359499335, 2059.3718915730697, 2070.4616799632054, 2071.4717746974575, 2077.511784730587, 2076.532780124475, 2137.262644387684, 2138.6257404954226, 2140.090461553849, 2141.593097311506, 6751.223609284946, 6827.897581536281, 6833.0, 6841.760538381182, 6847.349501797321, 4221.0, 4280.743155410685, 4308.935365969482, 4312.611864932172, 4313.28830742927, 2771.514984877638, 2808.578713193017, 2839.6592408911065, 2860.442213768316, 2864.2266588021325, 871.3856731004627, 873.1661263493348, 894.7226359828474, 895.4269267668698, 895.6865097215922, 9281.0, 9355.906440935572, 9401.294902882242, 9436.433813971184, 9452.480113593749, 3731.6828069613844, 3787.905302367463, 3846.769193848378, 3920.627540383659, 3941.4481972523868, 7866.685332586632, 7871.330936870316, 7880.369570434352, 7882.132965373804, 7885.44801863864, 4266.232078225949, 4268.167707297047, 4292.699511034198, 4297.892552686145, 4314.201872653569, 3938.232044751246, 3959.425883246971, 3960.6237407446247, 3971.781552014667, 3991.9002024949027, 3102.6276846778533, 3135.2005238663996, 3145.858436370369, 3155.3058327167987, 3160.004598551449, 932.654917680449, 948.4025064209642, 956.3879117364892, 966.5167416291854, 968.8837339519162, 864.3690106222367, 864.4337958637103, 872.520113937332, 987.5654711926729, 1166.2710805746408, 6490.967545162339, 6494.480441564663, 6515.348465153542, 6535.281119076898, 6572.211334639874, 951.6003278622857, 1020.0921180149672, 1076.047697787448, 1088.9074428673493, 1097.2154909239932, 5258.1849813279005, 5319.840404787863, 5325.840224793263, 5355.303810504687, 5358.089124848785, 8397.70608028716, 8499.872501912476, 8575.656973721107, 8645.654173833102, 8653.350998674998, 8388.664453421918, 8422.915770842335, 8443.029051659016, 8496.957515212385, 8571.142885711379, 6358.459530939829, 6541.34586541342, 6560.671966401661, 6561.835954101186, 6606.570572912745, 6670.799876003728, 6677.632730199885, 6689.899651505134, 6731.730730770714, 6769.1876974763345, 6879.105716256855, 6892.827679308058, 6900.447964162955, 7007.334303241184, 7021.262767409348, 7257.854842903007, 7571.772846814604, 7686.538807671557, 7689.231076892266, 7781.610919454118, 6232.532560057924, 6277.937220627735, 6295.96852015737, 6304.621722696469, 6350.872982540416, 6627.668616569056, 6694.966525167343, 6764.594124352555, 6783.898241526283, 6832.59004459734, 6292.874142517216, 6303.810885673437, 6338.8732225354315, 6408.359164083555, 6638.137042184485, 5756.625819321731, 6198.752049917953, 6215.65813880241, 6301.117843501822, 6302.180716506825, 6130.172426722335, 6165.10605962124, 6165.321814600301, 6175.783847565313, 6177.2587289525545, 5732.3121225453215, 5786.0453025252045, 5791.189233507396, 5810.128480727924, 5828.300603927556, 5842.737076831638, 5862.296524417097, 5865.706714664165, 5889.764409423575, 5934.7922822701], "unit": "score", "name": "mootools.html", "value": 4048.239944657324}, {"lowerIsBetter": false, "replicates": [299.9715027072428, 304.79730978899033, 309.3688874695621, 310.8570057773424, 312.20232306457, 5504.3394792624895, 5527.170924361338, 5590.217369568259, 5667.4049224831415, 5715.857103572413, 5530.364008139065, 5563.972180139093, 5566.832995010146, 5594.49649531542, 5601.80393686221, 4114.691398145136, 4250.149970005996, 4260.850870219528, 4269.786510674461, 4277.786110694468, 4226.830926762926, 4232.513260974981, 4234.06850492891, 4249.277622804116, 4261.339492378686, 4186.958130418688, 4197.097624010837, 4197.9370309445385, 4202.201581699467, 4206.348016057501, 4192.895177620553, 4206.0, 4208.032152604887, 4218.261804184286, 4219.599138081878, 3551.0944709099012, 3630.5824830144606, 3631.2918980798754, 3637.6907962823193, 3638.854445822164, 5275.366955965309, 5372.086745253317, 5397.082495975694, 5397.163439666858, 5428.891422171574, 4836.564709176156, 4846.079244943485, 4851.8301859435105, 4855.635827312965, 4856.465788763233, 5873.823785286449, 5879.8530036749, 5886.558508111909, 5887.970560147215, 5891.587588868781, 3851.2297540492013, 3932.8033598320258, 3953.525576930758, 3955.149642826806, 4013.5585085640555, 6120.693965301762, 6168.784092556783, 6176.227971503562, 6182.629042257479, 6187.504999600022, 4099.631033207026, 4120.299549076664, 4126.484189476316, 4128.298189307825, 4136.627703506639, 4169.499660040815, 4169.854055108057, 4171.520275168334, 4175.081482073939, 4183.707140500197, 4742.549457801503, 4831.637627177975, 4832.879178020507, 4837.661363704507, 4847.975760121177, 5887.381824908409, 5890.2342695449315, 5899.587028907935, 5901.3508514063415, 5903.321118071477, 1455.7015811758565, 1465.3991863335982, 1468.1631470062175, 1469.5150600301874, 1471.1026273972868, 972.8103019911191, 973.352720440913, 982.577491678585, 998.0568362897138, 998.7902540466438, 5068.923966140511, 5069.594432445396, 5074.340335756402, 5084.923726144111, 5088.363954505687, 6061.727222275008, 6112.786052488231, 6122.295935967331, 6128.693565321716, 6131.877362452726, 5017.322661440732, 5060.3168572242275, 5065.949340506621, 5073.492650734971, 5092.643514953992, 3209.422303985305, 3276.4430046892085, 3291.4240007998505, 3292.983535082309, 3295.7476159059406, 5611.186377975171, 5661.830145095653, 5702.0, 5711.828645140569, 5804.04233301509, 6337.2395312562785, 6338.429541341209, 6339.144215530937, 6339.714712837934, 6342.0, 5766.423357664284, 5774.422557744192, 5778.16216648592, 5779.595428319977, 5780.768769249183, 5993.340732519332, 6137.140800287874, 6172.567920245539, 6173.104899789595, 6176.413240742122, 6042.818715438544, 6074.210352654127, 6078.422549857756, 6079.696015199311, 6079.908801367895, 5808.680522571299, 5822.796202132905, 5832.795852145155, 5833.766649333979, 5842.474177324061, 4045.332520134145, 4052.574479679591, 4061.614146656063, 4072.490938632671, 4081.2245673321972, 6053.788117415869, 6150.630962142107, 6183.443490085914, 6189.164462797465, 6217.409346112112, 5913.142594323885, 5925.259342582177, 5927.407259274038, 5931.021381472009, 5941.257342832146, 5924.0, 5929.347771745191, 5936.22829032223, 5938.881222375615, 5939.495142912728, 5565.193047008081, 5765.106408506688, 5801.593888427769, 5807.651540907559, 5811.041178205549, 5972.104184372379, 5984.58107932458, 5985.6408615483215, 5987.13186587951, 5999.520038397005, 5833.183354330482, 5840.737166827418, 5842.795502157404, 5842.824715258549, 5844.707764611838], "unit": "score", "name": "prototype.html", "value": 4375.799745717653}, {"lowerIsBetter": false, "replicates": [697.3688811625477, 730.6931088942647, 733.9889901651476, 736.6206403702095, 785.387397829693, 10491.16070714343, 10763.0, 10818.0, 10833.187510936672, 10838.40388778617, 11841.230320029214, 11972.401379931012, 11976.401179941011, 11976.580819671313, 12021.639350819467, 625.6621424430801, 638.0493065332668, 793.6904607203195, 795.8090058385975, 807.8101646113158, 814.8533264012505, 816.7549735079482, 818.9836203275931, 820.819419727659, 830.6096134816656, 848.8344772769312, 849.0745087854237, 849.524266410812, 850.9106543812903, 855.3072011670536, 842.9114942930996, 845.1421806866036, 845.6955496021427, 848.2789628815519, 848.406115718993, 631.0817760159003, 631.5105793010454, 632.0140580694086, 632.032989526026, 632.1907957814005, 10931.0, 10968.890311096866, 11068.28056176346, 11079.0, 11082.168837337233, 11465.656030319104, 12008.339541325224, 12065.638030859089, 12160.695982600506, 12166.635000949986, 9561.808763824754, 9669.758256043584, 9716.31985760997, 10204.540795664212, 10242.334248273839, 2965.7182567656255, 2975.866086026134, 3040.923976900573, 3091.644460886982, 3284.5894263217097, 38467.80766096152, 38987.22025559501, 40325.39511907324, 41072.178556428706, 41101.0, 9430.622775089058, 9553.092456216647, 9572.090651388107, 9853.556589953469, 9866.80266394668, 16527.50417487477, 16575.502734917973, 16618.75071873923, 16684.58288542796, 16703.33186672544, 6472.967635161794, 6511.2512061113575, 6517.543771936013, 6568.86862262752, 6708.396244338033, 3658.908527286839, 3692.464592634053, 3702.629737026276, 3770.2271034437877, 3780.7920564368674, 2618.5548456762394, 2661.773749231298, 2666.8399896006304, 2667.6265322854815, 2670.7062223155435, 480.5074798331738, 484.2711718863089, 495.37582645865746, 502.70089296868304, 504.70979186966906, 506.35439814236406, 605.5276884030463, 611.4710775179437, 618.3723520626568, 618.8143556932902, 7893.921060789434, 7984.880226796487, 8006.839863202703, 8029.780369772925, 8071.0, 464.13670572734685, 610.2189197826789, 625.6965371794676, 629.1820633176852, 641.2113100885937, 3494.947575786365, 3533.5406397168204, 3584.910377240538, 3622.3117607654463, 3652.726045546595, 50278.0, 50360.496395036316, 50524.24213636798, 50643.493565064615, 50911.23633145506, 45654.77172614116, 45954.770226148656, 46360.5363946363, 46680.7665961668, 47047.52952470432, 41301.380479292835, 42019.57980420157, 42092.579074208865, 42191.0, 43174.56825431768, 7689.807754805951, 7697.538147711155, 7736.961315193388, 7921.485103468256, 8230.753077407688, 31897.202569935936, 32373.0, 33427.33145337031, 34029.65970340364, 34153.316933661685, 38411.2317753649, 38774.80612596919, 39050.0, 39220.215595688496, 39594.2081158381, 9371.906280937103, 9458.905410945803, 9518.381305215138, 9607.0, 10007.549660265451, 10850.511726972147, 11210.719732006764, 11316.0, 11373.54505819758, 11536.769264614828, 10505.212109092, 10515.895830937641, 10621.362718236931, 10815.35107893529, 11182.496787644413, 10383.94808025955, 10438.0, 10701.304415212986, 10703.19726020536, 10932.672019839418, 9924.652637157666, 10268.229882758973, 10747.462626868783, 10808.513616887416, 11018.39398833072, 9044.186023257938, 9101.726948191565, 9159.771005724644, 9176.58705358247, 9571.712848614552, 8779.195785986003, 8843.07147749477, 8878.334124940526, 8891.19979201875, 9116.997130315793], "unit": "score", "name": "yui.html", "value": 5756.028762704204}], "name": "dromaeo_css", "value": 6293.789091409828}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 108.0, 112.0, 112.0, 115.0, 124.0, 112.0, 112.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 113.4}, {"lowerIsBetter": true, "replicates": [162.0, 137.0, 153.0, 172.0, 141.0, 151.0, 155.0, 139.0, 171.0, 143.0], "unit": "score", "name": "audio-beat-detection", "value": 152.4}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 163.0, 160.0, 163.0, 166.0, 161.0, 162.0, 162.0, 165.0], "unit": "score", "name": "audio-dft", "value": 162.7}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 75.0, 73.0, 134.0, 74.0, 78.0, 72.0, 72.0, 89.0], "unit": "score", "name": "audio-fft", "value": 81.4}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 126.0, 94.0, 95.0, 96.0, 138.0, 94.0, 96.0, 130.0], "unit": "score", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 114.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.6}, {"lowerIsBetter": true, "replicates": [149.0, 149.0, 147.0, 148.0, 148.0, 147.0, 148.0, 148.0, 148.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.9}, {"lowerIsBetter": true, "replicates": [96.0, 98.0, 97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 98.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.8}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 73.0, 72.0, 72.0, 72.0, 72.0, 71.0, 70.0, 69.0], "unit": "score", "name": "json-parse-financial", "value": 71.6}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.5}, {"lowerIsBetter": true, "replicates": [84.0, 86.0, 88.0, 86.0, 96.0, 85.0, 84.0, 86.0, 88.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.9}, {"lowerIsBetter": true, "replicates": [132.0, 121.0, 124.0, 129.0, 127.0, 121.0, 123.0, 126.0, 130.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.7}, {"lowerIsBetter": true, "replicates": [139.0, 148.0, 151.0, 147.0, 148.0, 149.0, 149.0, 149.0, 144.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.3}, {"lowerIsBetter": true, "replicates": [71.0, 73.0, 71.0, 70.0, 71.0, 70.0, 69.0, 70.0, 71.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.5}], "name": "kraken", "value": 1540.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.14055490945339], "unit": "score", "name": "Richards", "value": 212.48273800000004}, {"lowerIsBetter": false, "replicates": [192.789666473877], "unit": "score", "name": "DeltaBlue", "value": 342.9540660000002}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5278.9473684210525], "unit": "score", "name": "Decrypt", "value": 50.423120638085756}, {"lowerIsBetter": false, "replicates": [1081.081081081081], "unit": "score", "name": "RayTrace", "value": 684.4898250000002}, {"lowerIsBetter": false, "replicates": [721.5007215007215], "unit": "score", "name": "Earley", "value": 923.7177179999996}, {"lowerIsBetter": false, "replicates": [12180.722891566265], "unit": "score", "name": "Boyer", "value": 54.714568743818035}, {"lowerIsBetter": false, "replicates": [26076.923076923078], "unit": "score", "name": "RegExp", "value": 34.934528023598794}, {"lowerIsBetter": false, "replicates": [608.6427267194157], "unit": "score", "name": "Splay", "value": 133.88971299999997}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21180.863303254988}]} 05:02:54 INFO - RETURN: dromaeo_css: 6293.79 05:02:54 INFO - RETURN: kraken: 1540.50 05:02:54 INFO - RETURN: v8_7: 21180.86 05:02:54 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21180.86"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1540.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6293.79"}}} 05:02:54 INFO - Return code: 0 05:02:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:02:54 INFO - # TBPL SUCCESS # 05:02:54 INFO - Running post-action listener: _resource_record_post_action 05:02:54 INFO - Running post-run listener: _resource_record_post_run 05:02:55 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 10436608; Write bytes: 420147200; Read time: 2712; Write time: 351384 05:02:55 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 20242432; Read time: 0; Write time: 35872 05:02:55 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 8699904; Write bytes: 399499264; Read time: 2672; Write time: 315452 05:02:55 INFO - Running post-run listener: _upload_blobber_files 05:02:55 INFO - Blob upload gear active. 05:02:55 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:02:55 INFO - Copying logs to upload dir... 05:02:55 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1275.735709 ========= master_lag: 33.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2015-12-15 05:03:28.425767) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 05:03:28.429398) ========= 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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009914 build_url: 'https://queue.taskcluster.net/v1/task/fLv6FrwuSQ-vEl2psVIDOQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.09 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 05:03:28.531590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 05:03:28.531918) ========= 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=61fa6ed41387771909ec0f71000001b3-1450183081.574634-1851369847 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.23 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 05:03:28.767568) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 05:03:28.767874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 05:03:28.769709) ========= ========= Total master_lag: 33.63 =========