builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1487599875.15 results: success (0) revision: 24931c1b6d9e5c0b1e496a2428f2012428715956 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.145241) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.145663) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.145938) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.162062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.162359) ========= 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=00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 06:11:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-02-20 06:11:15 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131914 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.307360) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.307671) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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=00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020210 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.356484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:15.356807) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 24931c1b6d9e5c0b1e496a2428f2012428715956 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 24931c1b6d9e5c0b1e496a2428f2012428715956 --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=00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 06:11:16,110 truncating revision to first 12 chars 2017-02-20 06:11:16,110 Setting DEBUG logging. 2017-02-20 06:11:16,110 attempt 1/10 2017-02-20 06:11:16,110 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/24931c1b6d9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 06:11:16,425 unpacking tar archive at: mozilla-central-24931c1b6d9e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639261 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:16.008325) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:16.008624) ========= script_repo_revision: 24931c1b6d9e5c0b1e496a2428f2012428715956 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:16.008982) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:16.009230) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 06:11:16.019161) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-02-20 06:11:16.019439) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:11:16 INFO - MultiFileLogger online at 20170220 06:11:16 in /builds/slave/test 06:11:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:11:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:11:16 INFO - {'append_to_log': False, 06:11:16 INFO - 'base_work_dir': '/builds/slave/test', 06:11:16 INFO - 'blob_upload_branch': 'Firefox', 06:11:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:11:16 INFO - 'branch': 'Firefox', 06:11:16 INFO - 'buildbot_json_path': 'buildprops.json', 06:11:16 INFO - 'config_files': ('talos/linux_config.py',), 06:11:16 INFO - 'default_actions': ('clobber', 06:11:16 INFO - 'read-buildbot-config', 06:11:16 INFO - 'download-and-extract', 06:11:16 INFO - 'populate-webroot', 06:11:16 INFO - 'create-virtualenv', 06:11:16 INFO - 'install', 06:11:16 INFO - 'run-tests'), 06:11:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:11:16 INFO - 'download_minidump_stackwalk': True, 06:11:16 INFO - 'download_symbols': 'ondemand', 06:11:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:11:16 INFO - 'tooltool.py': '/tools/tooltool.py', 06:11:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:11:16 INFO - '/tools/misc-python/virtualenv.py')}, 06:11:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:11:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:11:16 INFO - 'gecko_profile': False, 06:11:16 INFO - 'gecko_profile_interval': 0, 06:11:16 INFO - 'installer_path': 'installer.exe', 06:11:16 INFO - 'log_level': 'info', 06:11:16 INFO - 'log_name': 'talos', 06:11:16 INFO - 'log_to_console': True, 06:11:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:11:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:11:16 INFO - 'opt_config_files': (), 06:11:16 INFO - 'pip_index': False, 06:11:16 INFO - 'suite': 'dromaeojs', 06:11:16 INFO - 'system_bits': '32', 06:11:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:11:16 INFO - 'title': 'talos-linux64-ix-016', 06:11:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:11:16 INFO - 'use_talos_json': True, 06:11:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:11:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:11:16 INFO - 'work_dir': 'build'} 06:11:16 INFO - [mozharness: 2017-02-20 14:11:16.871500Z] Running clobber step. 06:11:16 INFO - Running pre-action listener: _resource_record_pre_action 06:11:16 INFO - Running main action method: clobber 06:11:16 INFO - rmtree: /builds/slave/test/build 06:11:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:11:17 INFO - Running post-action listener: _resource_record_post_action 06:11:17 INFO - [mozharness: 2017-02-20 14:11:17.790768Z] Finished clobber step (success) 06:11:17 INFO - [mozharness: 2017-02-20 14:11:17.790868Z] Running read-buildbot-config step. 06:11:17 INFO - Running pre-action listener: _resource_record_pre_action 06:11:17 INFO - Running main action method: read_buildbot_config 06:11:17 INFO - Using buildbot properties: 06:11:17 INFO - { 06:11:17 INFO - "project": "", 06:11:17 INFO - "product": "firefox", 06:11:17 INFO - "who": "cbook@mozilla.com", 06:11:17 INFO - "installer_path": "public/build/target.tar.bz2", 06:11:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 06:11:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:11:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 06:11:17 INFO - "stage_platform": "linux64-pgo", 06:11:17 INFO - "basedir": "/builds/slave/test", 06:11:17 INFO - "buildnumber": 44, 06:11:17 INFO - "platform": "ubuntu64_hw", 06:11:17 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:11:17 INFO - "slavebuilddir": "test", 06:11:17 INFO - "taskId": "Y0Vj4n2QRB-DO8cyDNRuBQ", 06:11:17 INFO - "branch": "mozilla-central", 06:11:17 INFO - "script_repo_revision": "production", 06:11:17 INFO - "revision": "24931c1b6d9e5c0b1e496a2428f2012428715956", 06:11:17 INFO - "slavename": "talos-linux64-ix-016", 06:11:17 INFO - "repo_path": "mozilla-central" 06:11:17 INFO - } 06:11:17 INFO - Finding installer, test and symbols from parent task. 06:11:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0Vj4n2QRB-DO8cyDNRuBQ'}, attempt #1 06:11:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0Vj4n2QRB-DO8cyDNRuBQ'}, attempt #1 06:11:18 INFO - Task dependencies: BP61zoddRFCVRyWv_Fd5kQ 06:11:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ'}, attempt #1 06:11:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2 06:11:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/test_packages.json 06:11:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.crashreporter-symbols.zip 06:11:19 INFO - Running post-action listener: _resource_record_post_action 06:11:19 INFO - [mozharness: 2017-02-20 14:11:19.197277Z] Finished read-buildbot-config step (success) 06:11:19 INFO - [mozharness: 2017-02-20 14:11:19.197498Z] Running download-and-extract step. 06:11:19 INFO - Running pre-action listener: _resource_record_pre_action 06:11:19 INFO - Running main action method: download_and_extract 06:11:19 INFO - mkdir: /builds/slave/test/build/tests 06:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:19 INFO - trying https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.test_packages.json 06:11:19 INFO - Downloading https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:11:19 INFO - Downloaded 896 bytes. 06:11:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:11:19 INFO - Using the following test package requirements: 06:11:19 INFO - {u'common': [u'target.common.tests.zip'], 06:11:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:11:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:11:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:11:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:11:19 INFO - u'mozbase': [u'target.common.tests.zip'], 06:11:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:11:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:11:19 INFO - u'web-platform': [u'target.common.tests.zip', 06:11:19 INFO - u'target.web-platform.tests.zip'], 06:11:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:11:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:11:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.common.tests.zip 06:11:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:11:19 INFO - Fetch https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.common.tests.zip into memory 06:11:20 INFO - Expected file size: 59239577 06:11:20 INFO - Obtained file size: 59239577 06:11:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:11:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.common.tests.zip 06:11:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:11:30 INFO - Fetch https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.common.tests.zip into memory 06:11:31 INFO - Expected file size: 59239577 06:11:31 INFO - Obtained file size: 59239577 06:11:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.talos.tests.zip 06:11:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:11:42 INFO - Fetch https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.talos.tests.zip into memory 06:11:42 INFO - Expected file size: 14404319 06:11:42 INFO - Obtained file size: 14404319 06:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:43 INFO - trying https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2 06:11:43 INFO - Downloading https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:11:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:11:44 INFO - Downloaded 66752316 bytes. 06:11:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2 06:11:44 INFO - mkdir: /builds/slave/test/properties 06:11:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:11:44 INFO - Writing to file /builds/slave/test/properties/build_url 06:11:44 INFO - Contents: 06:11:44 INFO - build_url:https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2 06:11:44 INFO - Running post-action listener: _resource_record_post_action 06:11:44 INFO - Running post-action listener: set_extra_try_arguments 06:11:44 INFO - [mozharness: 2017-02-20 14:11:44.267031Z] Finished download-and-extract step (success) 06:11:44 INFO - [mozharness: 2017-02-20 14:11:44.267127Z] Running populate-webroot step. 06:11:44 INFO - Running pre-action listener: _resource_record_pre_action 06:11:44 INFO - Running main action method: populate_webroot 06:11:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:11:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:11:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:11:44 INFO - '--tppagecycles', 06:11:44 INFO - '7'], 06:11:44 INFO - 'tests': ['tsvgm']}}, 06:11:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:11:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:11:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:11:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:11:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['damp', 'tps']}, 06:11:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'tests': ['damp', 'tps']}, 06:11:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['dromaeo_dom']}, 06:11:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:11:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['basic_compositor_video']}, 06:11:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:11:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['a11yr', 06:11:44 INFO - 'ts_paint', 06:11:44 INFO - 'tpaint', 06:11:44 INFO - 'sessionrestore', 06:11:44 INFO - 'sessionrestore_no_auto_restore', 06:11:44 INFO - 'tabpaint']}, 06:11:44 INFO - 'other-e10s': {'tests': ['a11yr', 06:11:44 INFO - 'ts_paint', 06:11:44 INFO - 'tpaint', 06:11:44 INFO - 'sessionrestore', 06:11:44 INFO - 'sessionrestore_no_auto_restore', 06:11:44 INFO - 'tabpaint']}, 06:11:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:11:44 INFO - 'ts_paint', 06:11:44 INFO - 'tpaint', 06:11:44 INFO - 'sessionrestore', 06:11:44 INFO - 'sessionrestore_no_auto_restore', 06:11:44 INFO - 'tabpaint']}, 06:11:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:11:44 INFO - 'ts_paint', 06:11:44 INFO - 'tpaint', 06:11:44 INFO - 'sessionrestore', 06:11:44 INFO - 'sessionrestore_no_auto_restore', 06:11:44 INFO - 'tabpaint']}, 06:11:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['a11yr', 06:11:44 INFO - 'ts_paint', 06:11:44 INFO - 'tpaint', 06:11:44 INFO - 'sessionrestore', 06:11:44 INFO - 'sessionrestore_no_auto_restore', 06:11:44 INFO - 'tabpaint']}, 06:11:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['a11yr', 06:11:44 INFO - 'ts_paint', 06:11:44 INFO - 'tpaint', 06:11:44 INFO - 'sessionrestore', 06:11:44 INFO - 'sessionrestore_no_auto_restore', 06:11:44 INFO - 'tabpaint']}, 06:11:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['tsvgx', 06:11:44 INFO - 'tsvgr_opacity', 06:11:44 INFO - 'tart', 06:11:44 INFO - 'tscrollx', 06:11:44 INFO - 'cart', 06:11:44 INFO - 'tsvg_static']}, 06:11:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:11:44 INFO - 'tsvgr_opacity', 06:11:44 INFO - 'tart', 06:11:44 INFO - 'tscrollx', 06:11:44 INFO - 'cart', 06:11:44 INFO - 'tsvg_static']}, 06:11:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'talos_options': ['--disable-e10s'], 06:11:44 INFO - 'tests': ['tp5o']}, 06:11:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:11:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'talos_options': ['--disable-e10s', 06:11:44 INFO - '--xperf_path', 06:11:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:44 INFO - 'tests': ['tp5n']}, 06:11:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:11:44 INFO - 'talos_options': ['--xperf_path', 06:11:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:44 INFO - 'tests': ['tp5n']}}, 06:11:44 INFO - 'talos.zip': {'path': '', 06:11:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:11:44 INFO - Running post-action listener: _resource_record_post_action 06:11:44 INFO - [mozharness: 2017-02-20 14:11:44.280238Z] Finished populate-webroot step (success) 06:11:44 INFO - [mozharness: 2017-02-20 14:11:44.280330Z] Running create-virtualenv step. 06:11:44 INFO - Running pre-action listener: _resource_record_pre_action 06:11:44 INFO - Running main action method: create_virtualenv 06:11:44 INFO - Creating virtualenv /builds/slave/test/build/venv 06:11:44 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 06:11:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:11:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:11:44 INFO - Using real prefix '/usr' 06:11:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:11:44 INFO - Installing distribute.............................................................................................................................................................................................done. 06:11:46 INFO - Installing pip.................done. 06:11:46 INFO - Return code: 0 06:11:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:11:47 INFO - Reading from file tmpfile_stdout 06:11:47 INFO - Output received: 06:11:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:11:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 06:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 06:11:47 INFO - Using env: {'DISPLAY': ':0', 06:11:47 INFO - 'HOME': '/home/cltbld', 06:11:47 INFO - 'LANG': 'en_US.UTF-8', 06:11:47 INFO - 'LANGUAGE': 'en_US:en', 06:11:47 INFO - 'LOGNAME': 'cltbld', 06:11:47 INFO - 'MAIL': '/var/mail/cltbld', 06:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:47 INFO - 'MOZ_NO_REMOTE': '1', 06:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:47 INFO - 'NO_EM_RESTART': '1', 06:11:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:47 INFO - 'PWD': '/builds/slave/test', 06:11:47 INFO - 'SHELL': '/bin/bash', 06:11:47 INFO - 'SHLVL': '1', 06:11:47 INFO - 'TERM': 'linux', 06:11:47 INFO - 'TMOUT': '86400', 06:11:47 INFO - 'USER': 'cltbld', 06:11:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:47 INFO - '_': '/tools/buildbot/bin/python'} 06:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:47 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 06:11:47 INFO - Cleaning up... 06:11:47 INFO - Return code: 0 06:11:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 06:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 06:11:47 INFO - Using env: {'DISPLAY': ':0', 06:11:47 INFO - 'HOME': '/home/cltbld', 06:11:47 INFO - 'LANG': 'en_US.UTF-8', 06:11:47 INFO - 'LANGUAGE': 'en_US:en', 06:11:47 INFO - 'LOGNAME': 'cltbld', 06:11:47 INFO - 'MAIL': '/var/mail/cltbld', 06:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:47 INFO - 'MOZ_NO_REMOTE': '1', 06:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:47 INFO - 'NO_EM_RESTART': '1', 06:11:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:47 INFO - 'PWD': '/builds/slave/test', 06:11:47 INFO - 'SHELL': '/bin/bash', 06:11:47 INFO - 'SHLVL': '1', 06:11:47 INFO - 'TERM': 'linux', 06:11:47 INFO - 'TMOUT': '86400', 06:11:47 INFO - 'USER': 'cltbld', 06:11:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:47 INFO - '_': '/tools/buildbot/bin/python'} 06:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:47 INFO - Downloading/unpacking psutil>=3.1.1 06:11:47 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 06:11:47 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 06:11:47 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 06:11:47 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 06:11:47 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 06:11:47 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 06:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:11:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:49 INFO - Installing collected packages: psutil 06:11:49 INFO - Running setup.py install for psutil 06:11:49 INFO - building 'psutil._psutil_linux' extension 06:11:49 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 06:11:49 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 06:11:49 INFO - building 'psutil._psutil_posix' extension 06:11:49 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 06:11:50 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 06:11:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:50 INFO - Successfully installed psutil 06:11:50 INFO - Cleaning up... 06:11:50 INFO - Return code: 0 06:11:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 06:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 06:11:50 INFO - Using env: {'DISPLAY': ':0', 06:11:50 INFO - 'HOME': '/home/cltbld', 06:11:50 INFO - 'LANG': 'en_US.UTF-8', 06:11:50 INFO - 'LANGUAGE': 'en_US:en', 06:11:50 INFO - 'LOGNAME': 'cltbld', 06:11:50 INFO - 'MAIL': '/var/mail/cltbld', 06:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:50 INFO - 'MOZ_NO_REMOTE': '1', 06:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:50 INFO - 'NO_EM_RESTART': '1', 06:11:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:50 INFO - 'PWD': '/builds/slave/test', 06:11:50 INFO - 'SHELL': '/bin/bash', 06:11:50 INFO - 'SHLVL': '1', 06:11:50 INFO - 'TERM': 'linux', 06:11:50 INFO - 'TMOUT': '86400', 06:11:50 INFO - 'USER': 'cltbld', 06:11:50 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:50 INFO - '_': '/tools/buildbot/bin/python'} 06:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:11:50 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 06:11:50 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 06:11:50 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 06:11:50 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 06:11:50 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 06:11:50 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 06:11:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:11:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:11:52 INFO - Installing collected packages: mozsystemmonitor 06:11:52 INFO - Running setup.py install for mozsystemmonitor 06:11:52 INFO - Successfully installed mozsystemmonitor 06:11:52 INFO - Cleaning up... 06:11:52 INFO - Return code: 0 06:11:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:11:52 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:52 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1'] in /builds/slave/test/build 06:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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==2.5.1 06:11:52 INFO - Using env: {'DISPLAY': ':0', 06:11:52 INFO - 'HOME': '/home/cltbld', 06:11:52 INFO - 'LANG': 'en_US.UTF-8', 06:11:52 INFO - 'LANGUAGE': 'en_US:en', 06:11:52 INFO - 'LOGNAME': 'cltbld', 06:11:52 INFO - 'MAIL': '/var/mail/cltbld', 06:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:52 INFO - 'MOZ_NO_REMOTE': '1', 06:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:52 INFO - 'NO_EM_RESTART': '1', 06:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:52 INFO - 'PWD': '/builds/slave/test', 06:11:52 INFO - 'SHELL': '/bin/bash', 06:11:52 INFO - 'SHLVL': '1', 06:11:52 INFO - 'TERM': 'linux', 06:11:52 INFO - 'TMOUT': '86400', 06:11:52 INFO - 'USER': 'cltbld', 06:11:52 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:52 INFO - '_': '/tools/buildbot/bin/python'} 06:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:52 INFO - Downloading/unpacking jsonschema==2.5.1 06:11:52 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 06:11:52 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 06:11:52 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 06:11:52 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 06:11:52 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 06:11:52 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 06:11:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:11:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:55 INFO - Downloading functools32-3.2.3-2.tar.gz 06:11:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:11:55 INFO - warning: no files found matching '*.txt' 06:11:55 INFO - no previously-included directories found matching 'build' 06:11:55 INFO - no previously-included directories found matching 'dist' 06:11:55 INFO - no previously-included directories found matching '.git*' 06:11:55 INFO - Installing collected packages: jsonschema, functools32 06:11:55 INFO - Running setup.py install for functools32 06:11:55 INFO - warning: no files found matching '*.txt' 06:11:55 INFO - no previously-included directories found matching 'build' 06:11:55 INFO - no previously-included directories found matching 'dist' 06:11:55 INFO - no previously-included directories found matching '.git*' 06:11:55 INFO - Successfully installed jsonschema functools32 06:11:55 INFO - Cleaning up... 06:11:55 INFO - Return code: 0 06:11:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:11:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 functools32==3.2.3-2 06:11:55 INFO - Using env: {'DISPLAY': ':0', 06:11:55 INFO - 'HOME': '/home/cltbld', 06:11:55 INFO - 'LANG': 'en_US.UTF-8', 06:11:55 INFO - 'LANGUAGE': 'en_US:en', 06:11:55 INFO - 'LOGNAME': 'cltbld', 06:11:55 INFO - 'MAIL': '/var/mail/cltbld', 06:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:55 INFO - 'MOZ_NO_REMOTE': '1', 06:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:55 INFO - 'NO_EM_RESTART': '1', 06:11:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:55 INFO - 'PWD': '/builds/slave/test', 06:11:55 INFO - 'SHELL': '/bin/bash', 06:11:55 INFO - 'SHLVL': '1', 06:11:55 INFO - 'TERM': 'linux', 06:11:55 INFO - 'TMOUT': '86400', 06:11:55 INFO - 'USER': 'cltbld', 06:11:55 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:55 INFO - '_': '/tools/buildbot/bin/python'} 06:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:11:55 INFO - Cleaning up... 06:11:55 INFO - Return code: 0 06:11:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:11:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 06:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 06:11:55 INFO - Using env: {'DISPLAY': ':0', 06:11:55 INFO - 'HOME': '/home/cltbld', 06:11:55 INFO - 'LANG': 'en_US.UTF-8', 06:11:55 INFO - 'LANGUAGE': 'en_US:en', 06:11:55 INFO - 'LOGNAME': 'cltbld', 06:11:55 INFO - 'MAIL': '/var/mail/cltbld', 06:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:55 INFO - 'MOZ_NO_REMOTE': '1', 06:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:55 INFO - 'NO_EM_RESTART': '1', 06:11:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:55 INFO - 'PWD': '/builds/slave/test', 06:11:55 INFO - 'SHELL': '/bin/bash', 06:11:55 INFO - 'SHLVL': '1', 06:11:55 INFO - 'TERM': 'linux', 06:11:55 INFO - 'TMOUT': '86400', 06:11:55 INFO - 'USER': 'cltbld', 06:11:55 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:55 INFO - '_': '/tools/buildbot/bin/python'} 06:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:55 INFO - Downloading/unpacking blobuploader==1.2.4 06:11:55 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 06:11:55 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 06:11:55 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 06:11:55 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 06:11:55 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 06:11:55 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 06:11:57 INFO - Downloading blobuploader-1.2.4.tar.gz 06:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:11:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11: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 06:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:11:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:58 INFO - Downloading docopt-0.6.1.tar.gz 06:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:11:58 INFO - Installing collected packages: blobuploader, requests, docopt 06:11:58 INFO - Running setup.py install for blobuploader 06:11:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:11:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:11:58 INFO - Running setup.py install for requests 06:11:59 INFO - Running setup.py install for docopt 06:11:59 INFO - Successfully installed blobuploader requests docopt 06:11:59 INFO - Cleaning up... 06:11:59 INFO - Return code: 0 06:11:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 06:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --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 06:11:59 INFO - Using env: {'DISPLAY': ':0', 06:11:59 INFO - 'HOME': '/home/cltbld', 06:11:59 INFO - 'LANG': 'en_US.UTF-8', 06:11:59 INFO - 'LANGUAGE': 'en_US:en', 06:11:59 INFO - 'LOGNAME': 'cltbld', 06:11:59 INFO - 'MAIL': '/var/mail/cltbld', 06:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:59 INFO - 'MOZ_NO_REMOTE': '1', 06:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:11:59 INFO - 'NO_EM_RESTART': '1', 06:11:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:59 INFO - 'PWD': '/builds/slave/test', 06:11:59 INFO - 'SHELL': '/bin/bash', 06:11:59 INFO - 'SHLVL': '1', 06:11:59 INFO - 'TERM': 'linux', 06:11:59 INFO - 'TMOUT': '86400', 06:11:59 INFO - 'USER': 'cltbld', 06:11:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:59 INFO - '_': '/tools/buildbot/bin/python'} 06:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:59 INFO - Running setup.py (path:/tmp/pip-LwC74M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:59 INFO - Running setup.py (path:/tmp/pip-xyLyvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:59 INFO - Running setup.py (path:/tmp/pip-8BaoT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:59 INFO - Running setup.py (path:/tmp/pip-D4KTV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:59 INFO - Running setup.py (path:/tmp/pip-qY9gS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:59 INFO - Running setup.py (path:/tmp/pip-MGFmEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:59 INFO - Running setup.py (path:/tmp/pip-pq0Ij1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:59 INFO - Running setup.py (path:/tmp/pip-4twA5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:59 INFO - Running setup.py (path:/tmp/pip-aMIhFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:12:00 INFO - Running setup.py (path:/tmp/pip-y1GnOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:12:00 INFO - Running setup.py (path:/tmp/pip-vxGahT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:12:00 INFO - Running setup.py (path:/tmp/pip-liz0kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:12:00 INFO - Running setup.py (path:/tmp/pip-jUjLfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:12:00 INFO - Running setup.py (path:/tmp/pip-yrRaDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:12:00 INFO - Running setup.py (path:/tmp/pip-3MbiRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:12:00 INFO - Running setup.py (path:/tmp/pip-weqGNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:12:00 INFO - Running setup.py (path:/tmp/pip-vxNgeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:12:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:12:00 INFO - Running setup.py install for manifestparser 06:12:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:12:00 INFO - Running setup.py install for mozcrash 06:12:00 INFO - Running setup.py install for mozdebug 06:12:00 INFO - Running setup.py install for mozdevice 06:12:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Running setup.py install for mozfile 06:12:01 INFO - Running setup.py install for mozhttpd 06:12:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Running setup.py install for mozinfo 06:12:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Running setup.py install for mozInstall 06:12:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Running setup.py install for mozleak 06:12:01 INFO - Running setup.py install for mozlog 06:12:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Running setup.py install for moznetwork 06:12:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Running setup.py install for mozprocess 06:12:01 INFO - Running setup.py install for mozprofile 06:12:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:12:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:12:02 INFO - Running setup.py install for mozrunner 06:12:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:12:02 INFO - Running setup.py install for mozscreenshot 06:12:02 INFO - Running setup.py install for moztest 06:12:02 INFO - Running setup.py install for mozversion 06:12:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:12:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:12:02 INFO - Cleaning up... 06:12:02 INFO - Return code: 0 06:12:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:12:02 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:02 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 06:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 06:12:02 INFO - Using env: {'DISPLAY': ':0', 06:12:02 INFO - 'HOME': '/home/cltbld', 06:12:02 INFO - 'LANG': 'en_US.UTF-8', 06:12:02 INFO - 'LANGUAGE': 'en_US:en', 06:12:02 INFO - 'LOGNAME': 'cltbld', 06:12:02 INFO - 'MAIL': '/var/mail/cltbld', 06:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:02 INFO - 'MOZ_NO_REMOTE': '1', 06:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:12:02 INFO - 'NO_EM_RESTART': '1', 06:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:02 INFO - 'PWD': '/builds/slave/test', 06:12:02 INFO - 'SHELL': '/bin/bash', 06:12:02 INFO - 'SHLVL': '1', 06:12:02 INFO - 'TERM': 'linux', 06:12:02 INFO - 'TMOUT': '86400', 06:12:02 INFO - 'USER': 'cltbld', 06:12:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:02 INFO - '_': '/tools/buildbot/bin/python'} 06:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:12:02 INFO - Running setup.py (path:/tmp/pip-eP8qVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:12:02 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)) 06:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:12:02 INFO - Running setup.py (path:/tmp/pip-gBEjjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:12:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 06:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:12:02 INFO - Running setup.py (path:/tmp/pip-6Bs3g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:12:02 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)) 06:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:12:02 INFO - Running setup.py (path:/tmp/pip-E_bpLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:12:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 06:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:12:02 INFO - Running setup.py (path:/tmp/pip-0UOQ9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:12:03 INFO - Running setup.py (path:/tmp/pip-6XxNH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:12:03 INFO - Running setup.py (path:/tmp/pip-tVybth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:12:03 INFO - Running setup.py (path:/tmp/pip-LvjOBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:12:03 INFO - Running setup.py (path:/tmp/pip-xyHYiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:12:03 INFO - Running setup.py (path:/tmp/pip-SGZuUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:12:03 INFO - Running setup.py (path:/tmp/pip-zLMzyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:12:03 INFO - Running setup.py (path:/tmp/pip-O3b0he-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:12:03 INFO - Running setup.py (path:/tmp/pip-X7aW6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:12:03 INFO - Running setup.py (path:/tmp/pip-JSN9hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:12:03 INFO - Running setup.py (path:/tmp/pip-HWLZ3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:12:03 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:12:03 INFO - Running setup.py (path:/tmp/pip-iPeekR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 06:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:12:03 INFO - Running setup.py (path:/tmp/pip-HZdCcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:12:03 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)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:12:03 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)) 06:12:03 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:12:03 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:12:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:12: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 06:12: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 06:12: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 06:12: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 06:12: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 06:12: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 06:12:05 INFO - Downloading blessings-1.6.tar.gz 06:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:12:05 INFO - Installing collected packages: blessings 06:12:05 INFO - Running setup.py install for blessings 06:12:06 INFO - Successfully installed blessings 06:12:06 INFO - Cleaning up... 06:12:06 INFO - Return code: 0 06:12:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:12:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:12:06 INFO - Reading from file tmpfile_stdout 06:12:06 INFO - Current package versions: 06:12:06 INFO - argparse == 1.2.1 06:12:06 INFO - blessings == 1.6 06:12:06 INFO - blobuploader == 1.2.4 06:12:06 INFO - docopt == 0.6.1 06:12:06 INFO - functools32 == 3.2.3-2 06:12:06 INFO - jsonschema == 2.5.1 06:12:06 INFO - manifestparser == 1.1 06:12:06 INFO - mozInstall == 1.12 06:12:06 INFO - mozcrash == 1.0 06:12:06 INFO - mozdebug == 0.1 06:12:06 INFO - mozdevice == 0.49 06:12:06 INFO - mozfile == 1.2 06:12:06 INFO - mozhttpd == 0.7 06:12:06 INFO - mozinfo == 0.9 06:12:06 INFO - mozleak == 0.1 06:12:06 INFO - mozlog == 3.4 06:12:06 INFO - moznetwork == 0.27 06:12:06 INFO - mozprocess == 0.25 06:12:06 INFO - mozprofile == 0.28 06:12:06 INFO - mozrunner == 6.13 06:12:06 INFO - mozscreenshot == 0.1 06:12:06 INFO - mozsystemmonitor == 0.3 06:12:06 INFO - moztest == 0.8 06:12:06 INFO - mozversion == 1.4 06:12:06 INFO - psutil == 3.1.1 06:12:06 INFO - requests == 1.2.3 06:12:06 INFO - wsgiref == 0.1.2 06:12:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 06:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 06:12:06 INFO - Using env: {'DISPLAY': ':0', 06:12:06 INFO - 'HOME': '/home/cltbld', 06:12:06 INFO - 'LANG': 'en_US.UTF-8', 06:12:06 INFO - 'LANGUAGE': 'en_US:en', 06:12:06 INFO - 'LOGNAME': 'cltbld', 06:12:06 INFO - 'MAIL': '/var/mail/cltbld', 06:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:06 INFO - 'MOZ_NO_REMOTE': '1', 06:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:12:06 INFO - 'NO_EM_RESTART': '1', 06:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:06 INFO - 'PWD': '/builds/slave/test', 06:12:06 INFO - 'SHELL': '/bin/bash', 06:12:06 INFO - 'SHLVL': '1', 06:12:06 INFO - 'TERM': 'linux', 06:12:06 INFO - 'TMOUT': '86400', 06:12:06 INFO - 'USER': 'cltbld', 06:12:06 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:06 INFO - '_': '/tools/buildbot/bin/python'} 06:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:12:06 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 2)) 06:12:06 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 3)) 06:12:06 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 4)) 06:12:06 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 5)) 06:12:06 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 6)) 06:12:06 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 7)) 06:12:06 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 8)) 06:12:06 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 9)) 06:12:06 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:12:06 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 4)) 06:12:06 INFO - Cleaning up... 06:12:06 INFO - Return code: 0 06:12:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x12391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1276a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d7310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, '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': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', '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 06:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 06:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 06:12:06 INFO - Using env: {'DISPLAY': ':0', 06:12:06 INFO - 'HOME': '/home/cltbld', 06:12:06 INFO - 'LANG': 'en_US.UTF-8', 06:12:06 INFO - 'LANGUAGE': 'en_US:en', 06:12:06 INFO - 'LOGNAME': 'cltbld', 06:12:06 INFO - 'MAIL': '/var/mail/cltbld', 06:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:06 INFO - 'MOZ_NO_REMOTE': '1', 06:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:12:06 INFO - 'NO_EM_RESTART': '1', 06:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:06 INFO - 'PWD': '/builds/slave/test', 06:12:06 INFO - 'SHELL': '/bin/bash', 06:12:06 INFO - 'SHLVL': '1', 06:12:06 INFO - 'TERM': 'linux', 06:12:06 INFO - 'TMOUT': '86400', 06:12:06 INFO - 'USER': 'cltbld', 06:12:06 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:06 INFO - '_': '/tools/buildbot/bin/python'} 06:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:12:06 INFO - Cleaning up... 06:12:06 INFO - Return code: 0 06:12:06 INFO - Running post-action listener: _resource_record_post_action 06:12:06 INFO - Running post-action listener: _start_resource_monitoring 06:12:06 INFO - Starting resource monitoring. 06:12:06 INFO - [mozharness: 2017-02-20 14:12:06.898976Z] Finished create-virtualenv step (success) 06:12:06 INFO - [mozharness: 2017-02-20 14:12:06.899444Z] Running install step. 06:12:06 INFO - Running pre-action listener: _resource_record_pre_action 06:12:06 INFO - Running main action method: install 06:12:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:12:07 INFO - Reading from file tmpfile_stdout 06:12:07 INFO - Detecting whether we're running mozinstall >=1.0... 06:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:12:07 INFO - Reading from file tmpfile_stdout 06:12:07 INFO - Output received: 06:12:07 INFO - Usage: mozinstall [options] installer 06:12:07 INFO - Options: 06:12:07 INFO - -h, --help show this help message and exit 06:12:07 INFO - -d DEST, --destination=DEST 06:12:07 INFO - Directory to install application into. [default: 06:12:07 INFO - "/builds/slave/test"] 06:12:07 INFO - --app=APP Application being installed. [default: firefox] 06:12:07 INFO - mkdir: /builds/slave/test/build/application 06:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:12:23 INFO - Reading from file tmpfile_stdout 06:12:23 INFO - Output received: 06:12:23 INFO - /builds/slave/test/build/application/firefox/firefox 06:12:23 INFO - Running post-action listener: _resource_record_post_action 06:12:23 INFO - [mozharness: 2017-02-20 14:12:23.826388Z] Finished install step (success) 06:12:23 INFO - [mozharness: 2017-02-20 14:12:23.826665Z] Running run-tests step. 06:12:23 INFO - Running pre-action listener: _resource_record_pre_action 06:12:23 INFO - Running main action method: run_tests 06:12:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:12:23 INFO - Python 2.7.3 06:12:23 INFO - Return code: 0 06:12:23 INFO - grabbing minidump binary from tooltool 06:12:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:23 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d4400>, '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 0x14d53b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d5840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:12:23 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 06:12:23 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 06:12:23 INFO - Calling ['/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'] with output_timeout 600 06:12:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:12:23 INFO - Return code: 0 06:12:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:12:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:12:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:12:23 INFO - ENV: RUST_BACKTRACE is now 1 06:12:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:12:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:12:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:12:23 INFO - Using env: {'DISPLAY': ':0', 06:12:23 INFO - 'HOME': '/home/cltbld', 06:12:23 INFO - 'LANG': 'en_US.UTF-8', 06:12:23 INFO - 'LANGUAGE': 'en_US:en', 06:12:23 INFO - 'LOGNAME': 'cltbld', 06:12:23 INFO - 'MAIL': '/var/mail/cltbld', 06:12:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:12:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:23 INFO - 'MOZ_NO_REMOTE': '1', 06:12:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:12:23 INFO - 'NO_EM_RESTART': '1', 06:12:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:23 INFO - 'PWD': '/builds/slave/test', 06:12:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:12:23 INFO - 'RUST_BACKTRACE': '1', 06:12:23 INFO - 'SHELL': '/bin/bash', 06:12:23 INFO - 'SHLVL': '1', 06:12:23 INFO - 'TERM': 'linux', 06:12:23 INFO - 'TMOUT': '86400', 06:12:23 INFO - 'USER': 'cltbld', 06:12:23 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120', 06:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:23 INFO - '_': '/tools/buildbot/bin/python'} 06:12:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:12:24 INFO - mozversion application_buildid: 20170220130544 06:12:24 INFO - mozversion application_changeset: 24931c1b6d9e5c0b1e496a2428f2012428715956 06:12:24 INFO - mozversion application_display_name: Nightly 06:12:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:12:24 INFO - mozversion application_name: Firefox 06:12:24 INFO - mozversion application_remotingname: firefox 06:12:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:12:24 INFO - mozversion application_vendor: Mozilla 06:12:24 INFO - mozversion application_version: 54.0a1 06:12:24 INFO - mozversion platform_buildid: 20170220130544 06:12:24 INFO - mozversion platform_changeset: 24931c1b6d9e5c0b1e496a2428f2012428715956 06:12:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:12:24 INFO - mozversion platform_version: 54.0a1 06:12:24 INFO - using testdate: 1487599944 06:12:24 INFO - actual date: 1487599944 06:12:24 INFO - starting webserver on 'localhost:57818' 06:12:24 INFO - SUITE-START | Running 2 tests 06:12:24 INFO - TEST-START | dromaeo_css 06:12:24 INFO - operating with platform_type : linux_ 06:12:24 INFO - Initialising browser for dromaeo_css test... 06:12:24 INFO - TEST-INFO | started process 30138 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSz9Ds1/profile http://localhost:57818/getInfo.html) 06:12:27 INFO - PROCESS | 30138 | Iterating over profile /tmp/tmpSz9Ds1/profile 06:12:27 INFO - PROCESS | 30138 | __metrics Screen width/height:1600/1200 06:12:27 INFO - PROCESS | 30138 | colorDepth:24 06:12:27 INFO - PROCESS | 30138 | Browser inner width/height: 1024/697 06:12:27 INFO - PROCESS | 30138 | __metrics 06:12:27 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:27 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/secmod.db = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/key3.db = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/webappsstore.sqlite = Mon Feb 20 2017 06:12:27 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/prefs.js = Mon Feb 20 2017 06:11:42 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/compatibility.ini = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/mimeTypes.rdf = Mon Feb 20 2017 06:12:27 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/permissions.sqlite = Mon Feb 20 2017 06:12:25 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/gmp = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/cookies.sqlite = Mon Feb 20 2017 06:12:27 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/sessionCheckpoints.json = Mon Feb 20 2017 06:12:27 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/addons.json = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/directoryLinks.json = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/safebrowsing = Mon Feb 20 2017 06:12:28 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/content-prefs.sqlite = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/places.sqlite = Mon Feb 20 2017 06:12:27 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/crashes = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/pluginreg.dat = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | Stat failure 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/places.sqlite-shm = Mon Feb 20 2017 06:12:25 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | Stat failure 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/places.sqlite-wal = Mon Feb 20 2017 06:12:28 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/frequencyCap.json = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/bookmarkbackups = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/cache2 = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/minidumps = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/thumbnails = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/.parentlock = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/blocklist.xml = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/extensions.ini = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/user.js = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/cert8.db = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/startupCache = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/extensions = Mon Feb 20 2017 06:12:24 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/localstore.rdf = Mon Feb 20 2017 06:11:42 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | No birth date. Using mtime. 06:12:28 INFO - PROCESS | 30138 | Using date: /tmp/tmpSz9Ds1/profile/extensions.json = Mon Feb 20 2017 06:12:26 GMT-0800 (PST) 06:12:28 INFO - PROCESS | 30138 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:12:28 INFO - PROCESS | 30138 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:12:29 INFO - TEST-INFO | 30138: exit 0 06:12:29 INFO - Browser initialized. 06:12:29 INFO - Running cycle 1/1 for dromaeo_css test... 06:12:29 INFO - TEST-INFO | started process 30230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSz9Ds1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:41 INFO - PROCESS | 30230 | RSS: Main: 142946304 06:12:41 INFO - PROCESS | 30230 | 06:15:43 INFO - PROCESS | 30230 | Cycle 1(1): loaded http://localhost:57818/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57818/tests/dromaeo/cssquery-ext.html) 06:15:45 INFO - PROCESS | 30230 | RSS: Main: 348332032 06:15:45 INFO - PROCESS | 30230 | 06:17:30 INFO - PROCESS | 30230 | ************************* 06:17:30 INFO - PROCESS | 30230 | A coding exception was thrown and uncaught in a Task. 06:17:30 INFO - PROCESS | 30230 | 06:17:30 ERROR - PROCESS | 30230 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:17:30 INFO - PROCESS | 30230 | Full stack: 06:17:30 INFO - PROCESS | 30230 | ************************* 06:18:48 INFO - PROCESS | 30230 | Cycle 1(1): loaded http://localhost:57818/tests/dromaeo/cssquery-ext.html (next: http://localhost:57818/tests/dromaeo/cssquery-jquery.html) 06:18:48 INFO - PROCESS | 30230 | RSS: Main: 237924352 06:18:48 INFO - PROCESS | 30230 | 06:21:50 INFO - PROCESS | 30230 | Cycle 1(1): loaded http://localhost:57818/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57818/tests/dromaeo/cssquery-mootools.html) 06:21:51 INFO - PROCESS | 30230 | RSS: Main: 226996224 06:21:51 INFO - PROCESS | 30230 | 06:24:53 INFO - PROCESS | 30230 | Cycle 1(1): loaded http://localhost:57818/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57818/tests/dromaeo/cssquery-prototype.html) 06:24:53 INFO - PROCESS | 30230 | RSS: Main: 182648832 06:24:53 INFO - PROCESS | 30230 | 06:27:56 INFO - PROCESS | 30230 | Cycle 1(1): loaded http://localhost:57818/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57818/tests/dromaeo/cssquery-yui.html) 06:27:57 INFO - PROCESS | 30230 | RSS: Main: 187584512 06:27:57 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | Cycle 1(1): loaded http://localhost:57818/tests/dromaeo/cssquery-yui.html (next: http://localhost:57818/tests/dromaeo/cssquery-dojo.html) 06:31:00 INFO - PROCESS | 30230 | RSS: Main: 275144704 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | __start_tp_report 06:31:00 INFO - PROCESS | 30230 | _x_x_mozilla_page_load 06:31:00 INFO - PROCESS | 30230 | _x_x_mozilla_page_load_details 06:31:00 INFO - PROCESS | 30230 | |i|pagename|runs| 06:31:00 INFO - PROCESS | 30230 | |0;dojo.html;3114.2681469854583;3143.2613335866067;3149.511825667021;3167.572377729006;3196.920076998075;5039.924401133986;5113.565346945514;5135.281060651511;5178.0938335791225;5230.293910322105;5219.45195754446;5228.215767634857;5250.343707036621;5262.05283049051;5279.815206467765;4594.74728889911;4606.97696511517;4612.492625811166;4780.737059461728;4819.277108433728;4805.447373552051;4818.9036219275595;4861.416630004404;4862.951370486286;4875.171220892456;4564.931526027096;4599.195140850371;4604.148232576984;4664.323673067386;4850.53919877611;4623.884402889921;4627.3521706961055;4641.419822522184;4660.5339466053465;4703.905921881543;4691.413573303337;4699.483056863743;4746.83386081489;4749.24012158053;4760.262159365304;5236.607254455931;5262.447443018466;5268.262443257909;5285.524302812727;5318.707471089088;5450.672959622435;5490.505854473076;5698.914516282259;5710.229119068914;5715;5553.805616803393;5612.102063669834;5616.747246373923;5621.269234999465;5625.578081643893;5206.973965130188;5250.976059668366;5251.789928402859;5260.736963151827;5262.079136151158;5764.481196692318;5765.164051212634;5807.709614519342;5812.3025236971835;5818.21454103699;5357;5392.730363481889;5398.676079435247;5551.9722401387735;5553.77784888608;5536.7231638418725;5543.61194716366;5544.500994910478;5545;5553.194786755897;5510.669359838343;5516.172574113834;5520.558355331564;5531.446855314517;5602.215689803431;5642.266505354278;5666.744996475169;5670.886582268414;5676.489115979499;5683.914741278884;4202.768847713405;4230.830766769356;4354.586314300136;4420.9115817684105;4427.756473393994;3956.604339566078;3981.064449854282;3981.6018398159954;3985.9202815943518;3990.5809889961715;5471.425500322488;5477.835664930059;5480.561555075585;5482.2873026506295;5485.945140548623;5427.185922111636;5438.510534051954;5701.144828275709;5741.1388291756575;5752.022156233366;5281.12861377876;5295.046891559557;5295.417504074549;5296.761645725875;5299.072662284085;4770.904581908342;4777.522247775195;4782.856514304576;4845.927311090337;4944.851654450372;6045.214122164267;6051.546134039964;6054.3340232575265;6087.36082711309;6100.298465676416;5749.798757043483;5751.424857514216;5756.798512052058;5758.510526605118;5759.65442073477;5694.886102278014;5746.281714785652;5748.9425105748405;6036.547258955508;6044.24446944132;5441.646292990943;5444.183372494158;5446;5450.073487507054;5485.040117979417;5494.697791621499;5518.255035570147;5647.265855438931;5656.595946722544;5763.452472015152;5587.525060369914;5588.245586845724;5629.634073785354;5631.831045068655;5632.85917852057;5338.946610533845;5570.916436253378;5577.079781836114;5579.944200558105;5584.329880414376;5334.946650533445;5440.727963601725;5454.236406902957;5455.509004189642;5516.531094856982;5643.71781410936;5700.543956483554;5704.80033198836;5716.056850619601;5727.942720572741;5481.561475082063;5504.972475137599;5514.035043867388;5557.305336832896;5566.443355664564 06:31:00 INFO - PROCESS | 30230 | |1;ext.html;574.9741261643226;587.7737071227579;595.6515438468497;600.3486217454063;601.0173366545697;30124.246893827636;41108;43073.78463107684;43162.25215226322;46354.76822615891;45594;46135;46149.30776038362;46565.767171164094;46580;9099.181073703365;9277.118673725985;9293.628254869796;9297.767555811124;9298.116678915492;7450.962745186266;7635.732749353774;10574.682759517227;10821.242513024092;10872.18458615603;10776.622818201364;10780.622678206264;10787.946060269727;10816.134709223243;10907.83638245427;10639.946800265989;10733.248672592921;10743.462826858626;10826.621068262652;10882.67351979434;10498.160147188206;10571.418571978538;10573.524191411405;10574.577016919315;10577.100946419563;40350;40547;40582.79708601438;40638;40835.38746918798;3761.6614504694708;3773.8867833965023;3788.7347885648014;3800.22092003784;3809.276237514863;27787.58318625222;30038.69961300381;30997.845010774803;32022.35955280881;32667.509987350208;3907.628775266345;3924.2936271471126;3931.1351502669654;3958.6041395860184;4145.2952997990415;277909;280675;280854;281809;282381;49235.26147107796;57834.132488012714;57971;58493.70753146207;58524.414755851896;49030;59475.40524594699;60918.69540652268;61100.694496527234;66551;14811.629709257139;15358.923205384124;15388.38446462129;15401.383944642314;15408.537743867702;11766.235194712317;11854.170208085352;11874.821877671842;11879.643610691694;11880.524779008916;14563.563093107225;14876.628084297763;16663.3334666612;17015.489535313962;17102;5947.167396564484;5953.196318496947;6024.126501657235;6075.33171351151;6111.755529778759;891.31814162166;901.756525738047;902.2691619787993;907.2560500389617;926.064674678566;29650.594094310436;30190.849045754632;30679;30937.381252375275;30966.380672386425;1004.924630652704;1069.2889228663007;1077.6982444915436;1089.9509522071367;1109.938953357557;15476.458323958608;15747.606309842116;15963.36146554148;16489.175541222892;16492.752608710878;420301;420664;422566;423758;424299;59647;59747.402525975056;59852;59855.70072149611;59943.70028149919;1337.164272329794;1392.7075314183933;1393.540131756518;1396.6438558167806;1398.4965412451509;28068.157955261377;29399.26501837471;29409.55885661674;29750;30287.697123029368;48956.51043489519;49427.752861235465;49460.75269623629;49496;49557;327889;328567;329190;329389;329460;36819.447708283864;37513.93450408176;37980.810095949346;38275.80862095672;38425.42361864518;36752.08119797026;37784.811075944446;43444.782776085915;43851.78074109737;45426.772866135456;38385.4242186373;39024.80487597544;39027.219455611295;40637.59362406338;40841.79579102086;38300;38386.0403489915;39501.40747888727;39542.40686389649;40562.79718601388;34708.47937280892;35404.29191416209;35579;37025.44461833128;39420;28065.43869122647;30241;31514.684853151175;31561.301969419434;33489.49765753564;24928.37679058038;25090.74909250957;25196.370090747878;25366.873165634053;25639.743602563736 06:31:00 INFO - PROCESS | 30230 | |2;jquery.html;426.34981652979224;426.93274593611557;432.66468486922633;437.6258299154223;447.47198305998927;17908.731369029458;18946.24215031397;19607.41177764666;19770.30803921863;19783.703244451328;20101.195952161932;20164.59670806583;20627.587448251026;20632.38102856913;20913.790862091373;5686.374498805118;6001;6008.969955150218;6013.849653758669;6018.15745795589;5761.423857614226;5864.091065884794;5870.178175055496;5892.852678683046;5965.522758179357;5865.35481097079;5866.853328666774;5866.88266234675;5877.353491115995;5879.382664820197;5709.143628455723;5795.2176456178295;5805.245318108661;5839.328477225131;5841.88316233673;5275.050490911634;5298;5307.893842123174;5310.7610157543;5315.176147697074;23018.66229396745;23255.18606848752;23308.883455582614;23419.531609367717;23543;2693.6498255226693;2728.1269993602054;2736.165469531792;2741.520233959049;2782.248792825946;14472.131672099707;14605.196714180716;14664.560063198014;14700.926495367561;15260.923695381563;1807.1777341309673;1856.4152292027968;1861.795202527721;1896.8861868287809;1905.5426697592616;80076;80429;81191.59404203;81630.59184704038;81864.59067704623;11353.318800871974;11423.942880285545;11607.36159511235;11749.88250117505;11786.646400607824;16144.112073836051;16174.926763914818;16227.269772860249;16227.350905963865;16247.350105995863;7678.96160519205;7852.764417067383;7966.8572342382795;8076.55578943152;8083.151269116658;7237.710491580277;7259.963700181571;7267.38227250679;7457.291557302047;7474.177840437547;7137.321954414322;7263.709451621981;7264.309890560494;7314.719924013383;7369.299916507923;2020.202020202012;2048.354768247997;2049.2008116834445;2089.7178880851193;2110.641190997534;852.8976522817178;856.5503110866845;871.1549796697194;873.6680061576687;874.4927941793744;17310.740338894928;17507.82492175062;17898.821011789714;18267.63464730698;18417.421560981475;879.4679219072497;884.7478468636408;889.479654402182;894.3784070071238;904.7059705595708;9928.305018648769;10717.142628589694;10807.83788243177;10844.403557804397;10874.184436167161;603658;608505;613078;621552;624401;57579.712101439225;70512;72602.27397726061;76966.6151669238;78298.6085069571;1090.8527348807809;1116.1740312168936;1117.541807858774;1129.5651174297955;1139.6865861888048;15898.841011590199;16135.75796363032;16507.174641268128;16622.916885415496;16806.243719032427;37135.25729485449;37473.6252637481;37888.62111378961;38002.23995520019;38552;48180;49895.259950304666;50052.749736251084;50145.74927125341;52496.47503524916;8734.69428569997;8743.38796284254;8832.602532885907;8925.687600933936;9026.638934442548;12755.681107972376;12799.168054076456;12890.935545322214;12912.870871291543;12923.28921909304;11537.307761534335;11606.593769218036;11654.883451165719;11693;12197.329146897006;11966.12702366127;12001.699957500783;12160.513579457076;12177.573784917486;12189.634310970685;12230.694232643898;12285.570005050138;12288.508459661514;12456.688582785502;12470.875291247334;8731.170538798804;8845.115488451103;8899.688510902088;9092.636294548409;9263.351565390358;11195;11209.71975700614;11868.82196767032;12031.217970831867;12116.394180291127 06:31:00 INFO - PROCESS | 30230 | |3;mootools.html;1251.349298364851;1427.5360507834953;1432.5057855040006;1447.6525633847873;1460.2917584971294;7263;8199.262066414021;8366.53984030878;8470.322374210064;8693.261072808804;8547.10255423181;8566.871496927552;8582.742517724462;8585;8631.136886311382;1883.7739471263399;1953.794851540589;2237.2169740590825;2274.3404412720374;2275.4538910661486;2196.088623221361;2272.3750968483632;2275.2150508074687;2289.5535370602656;2297.9195728149516;2173.1741938063515;2195.549912267981;2197.6923230747707;2199.571083638683;2206.569718904814;2222.9777702223014;2236.9217077402213;2237.149883044449;2242.921497747587;2243.562505311457;2142.2180903970093;2171.3160354488286;2173.826093912499;2185.2570126157184;2187.55155193185;8158.102608713037;8205;8207.261346478846;8214.096449390563;8249.587520623945;4890.73100979446;5015.32293140425;5027.522385373383;5028.647994640377;5035.2628343221895;3078.6305643322944;3225.2743132795167;3232.402005628943;3301.207710149547;3301.933961320784;1225.3138242584093;1334.0661536924192;1343.8992075594272;1388.7708528092853;1389.6456403617115;11340.829887551694;11437.942810285895;11438.714032149179;11447.942760286229;11456.885431145665;4272.679549033773;4464.2187617166865;4522.479914809774;4545.977270113628;4548.113117942035;9764.414135151772;9802.460864652512;9812;9814.263930205263;9814.312998090065;5176.7152806596;5178.741062946838;5180.274761533388;5180.404253510819;5188.870278243074;4677.017826256456;4697.906041879211;4712.057588482317;4789.760511974388;4805.37530121082;3696.5748938871834;3763.1344790698295;3781.829817658212;3838.2899163654815;3870.5548861881243;1227.551943540619;1242.7141757395668;1249.194269696041;1256.296473974577;1257.0509265504486;855.4268640011207;873.7990262239721;880.0847118996301;898.9595468203946;901.5176880368971;5244.164542410203;5256.579473642099;5284.735763211824;5340.786368545215;5508.14623733322;929.4469790474657;959.5921733263475;970.6360114956891;970.669972209438;972.5234635028927;3427.3830710471857;3602.531670882821;3744.6629803317833;3749.3063783200196;3764.0966168120003;9865.753356166153;10118;10132.189424845994;10143.594256229815;10205.183585313158;9940.751481212883;10070.496475176358;10071.194304455626;10074.899251007542;10095.24285678577;7424.368928641126;7621.961890190624;7739.419543534257;7882.7241046563095;8028.43800933929;5307.4427185145005;5335.279737235423;5367.382750983531;5438.700871452108;5577.44225577439;9180.724578262663;9275.953620231856;9309.813803724022;9309.906900930904;9311.627534898527;8211.302039326723;8934.73195804127;9278.768030799014;9287.67493137764;9427.528623568656;6485.189351331083;7098.893516597358;7215.2063273038875;7290.307420795016;7293.927060729537;8132.878006829784;8201.343892488705;8255.834883302181;8287.875681864656;8317.459365141247;7259.165196002316;7346.6326683666675;7349.375303099297;7411.370033547209;7446.218147094693;7247.8550428990075;7262.636868156676;7268.454865884974;7291.671874765542;7359.411247100326;6007.849803754941;6019.909701354571;6025.58398776296;6065.969670151621;6094.51243900478;4674.579287864109;4696.131215725101;4751;4798.992211635596;4827.782749776198;4679.344891715231;4724.6692731508465;4799.3041009052795;4847.3698419206685;4876.317315575751 06:31:00 INFO - PROCESS | 30230 | |4;prototype.html;405;405.9594040595941;407.1328071208326;409.5699515508715;413.93583994480866;5183.663061900978;5201.661891977019;5228.137357336044;5274.920876186856;5290.973545132279;4957.925631115527;5066.695998240109;5097.796088156479;5177.637565370425;5180.274761533388;4088.6115818997146;4100.200460910124;4100.626290192789;4102.856400025999;4123.216588848124;4117.56765539617;4131.194417088669;4134.842244171637;4158.688098392618;4172.791360431981;3790.9810450947707;3960.7423768451995;4152.750834949898;4173.455821346091;4182;3993.4808474898223;4009.859654912064;4015.538213105501;4015.9397609035595;4024.416459613369;3581.194231297963;3593.1735700788704;3601.135727425399;3608.350496910555;3617.3488772021024;5070.670406423571;5111.2333150027425;5119.59043276537;5131.3329267195395;5150.845474635767;4485.304777759453;4638.304254361839;4651.465081515601;4690.132325519756;4691.249400096002;5495.2856128703415;5499.477549632778;5500.202470641774;5509.889802203974;5522.171674248895;4017.799110044486;4017.859374921893;4048.6356227939627;4051.3315302974975;4052.837886484537;5395.29861118056;5477.315335583052;5543.695096769676;5656.878670498563;5688.971555142198;3946.2304850553865;3948.8153553933703;3953.446517487554;3960.881173564798;3964.5044369453817;4059.9797001014804;4084.0402505411203;4114.6091121343425;4123.0516981094515;4144.896377590584;4538.387317712132;4629.837955671535;4630.513796051433;4656.743879086615;4672.672912896132;5423.511883930386;5446.5414461816235;5477.096279113981;5482.533984611273;5511.3937466878615;1356.2608378433777;1363.6431750408408;1373.9175649461065;1385.9930700346433;1391.7773156294945;933.6498812945146;971.7959228562081;973.5667627905656;977.5894124467741;988.2933702402817;5114.028334616411;5166.896662066738;5179.2291124249095;5194.766250512241;5240.4759524047295;5373.844623405908;5459.781608735686;5498.120300751861;5504.4770746778995;5524.3370795504;4932.926006109911;5117.232415137684;5123.436421993578;5139.383274007143;5159.535641792181;2861.7138286171635;2866.4840328741025;2872.3824377758883;2874.2754347391397;2898.347871728844;5686.289213848269;5696.401877802854;5706.800261990894;5730.7421166047625;5751.281089863767;5223.425423203445;5538.252335934597;5540.2243685884005;5540.2243685884005;5561.63849349791;5677.37548869624;5684.072702558268;5701.657900525982;5709.115087161496;5741.712914354349;5215.687058776485;5389.8113566025;5438.320209973754;5440.5038614381365;5455.399906010256;5382.381026182039;5385.492062222584;5386.353637563518;5396.192275587722;5404.783923617155;5521.199426083276;5666.640006398352;5721.485066344049;5725.7137143141845;5742.511886489695;4026.355783074694;4026.91946161081;4035;4052.311107111857;4073.7371414861486;5512.897420515993;5542.8337149883955;5547.667139971614;5562.638428502296;5578.609497335147;5185.536869986726;5420.701861397661;5445.972770136283;5446.836594902159;5450.5912056595125;5392.352917649907;5409.456056912212;5421.62048656606;5442.8639284016635;5452.591055670761;5229.13719236337;5359.892802144013;5366.7191443637075;5417.566594672496;5453.2365468833605;5379.757910894097;5399.541039011728;5416.51251387377;5419.187121931742;5425.430329815313;5288.497592728685;5291.973540132275;5321.243989483539;5325.881564871421;5361.785528578813 06:31:00 INFO - PROCESS | 30230 | |5;yui.html;718.8526352097821;773.6479901644752;801.8797180422936;833.7373727275909;837.401258100458;15049.172295523742;15954.920225398857;15958.122303273316;15979.600509987256;16009.759853602176;14512.129272243646;14616.415343386252;14648.560543183721;14667.119972801613;14684.632884177874;777.1179711027968;783.4163548156631;796.3350602247131;801.0947629179049;808.1352952340998;786.6224212378062;792.1247022040649;792.8850316704074;794.5868148562744;796.34699546372;828.3952714518405;829.6349606173295;835.1982097186677;835.5362773660615;835.5404527509876;831.264331067007;831.2726364431123;831.5592735850009;831.983360332796;832.2052439919862;609.0468416927495;613.1875265273486;613.4785432382483;613.6594190224424;613.7943788830747;14861.108333500024;15249.6187595311;15317.846821531864;15341.386344546205;15349.07905525672;14347;14419.927900360535;14599.70800583993;14950.40198392063;14959.850401496064;11961.162718609701;12008.63974080779;12146.514139434412;12371.566995155126;12637.684057898534;3691.53855768029;3703.3519134151684;3705.3886108792285;3783.451399547078;3794.3739283018267;47599;49401.25898111531;49514.752426237996;65976.67011664959;78267;11159.776804463865;11797.410129493492;11812.88187118135;11847.881521184678;12110.152289339661;21727.891360543414;21929.780702192773;21933.890330548566;22034.559308813736;22110.33668989933;8307.709230176915;8422.747317580483;8442.915570844336;8527.658893644184;8865.911340886638;4308.095299987038;4428.557144285545;4428.82284708608;4490.640748740093;4502.887427814331;3630.9092272692865;3653.6711695947492;3688.207035487383;3739.2521495700967;3747.45661879026;642.3095172689377;643.9967800161063;645.6803882078401;659.0378048049901;671.385682100882;745.7464462082918;757.9810504737426;763.3625922354896;790.2768966395683;808.1877712898499;9599.32804703664;10134.341267817716;10206.897931020594;10319.1744660427;10473;754.8150703077781;765.5062484697339;789.2265579731895;792.4373694676729;794.7059587952378;4584.88537786558;4763.976180119077;5325.868218135454;5545.168224766237;5708.457696518907;75741.62129189319;76104.61947690225;76779.61610191913;79974.60012700016;79991.20008799953;64249.67875160594;64461.67769161218;64699.35300646933;65060.674696626214;65378;59346;60061;62505.68747156235;63378.732425351234;63853.36146638474;9733.902660973583;10286.331388459726;10410.575505410321;10412.531436085546;10520.736981575521;39373.21253574855;39462.40806387964;39772.60227397689;41136.1772764549;41244.587554124075;47536.52463475321;48686;49513;50457.24314135365;52592.737036314575;13017.674558135743;13252.734945301232;13484.12353197039;13506.662333441742;13522.72954540884;13857.37641806101;13953.720925581634;14523.927380363031;14802;14818.110913345234;13881.79177312321;14094;14713.55859324222;15263.618409539851;15412.768808468107;13910.930445348113;14408.927955360155;14526.128432294096;14890.181040042467;15073;13539.661508462368;14116.435342586181;14553.212130811626;14599.635009124431;14755.483558075415;11538.192326537062;11672.88327116718;11823.408829558659;11980.10149238828;12248.448819802952;10806.891931080589;11723.179377443837;11972.58095966637;12003.579874704343;12004.819927701266 06:31:00 INFO - PROCESS | 30230 | __end_tp_report 06:31:00 INFO - PROCESS | 30230 | __start_cc_report 06:31:00 INFO - PROCESS | 30230 | _x_x_mozilla_cycle_collect,2262 06:31:00 INFO - PROCESS | 30230 | __end_cc_report 06:31:00 INFO - PROCESS | 30230 | __startTimestamp1487601060581__endTimestamp 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | ------- Summary: start ------- 06:31:00 INFO - PROCESS | 30230 | Number of tests: 6 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | [#0] dojo.html Cycles:165 Average:5239.72 Median:5450.67 stddev:598.88 (11.0%) stddev-sans-first:577.04 06:31:00 INFO - PROCESS | 30230 | Values: 3114.3 3143.3 3149.5 3167.6 3196.9 5039.9 5113.6 5135.3 5178.1 5230.3 5219.5 5228.2 5250.3 5262.1 5279.8 4594.7 4607.0 4612.5 4780.7 4819.3 4805.4 4818.9 4861.4 4863.0 4875.2 4564.9 4599.2 4604.1 4664.3 4850.5 4623.9 4627.4 4641.4 4660.5 4703.9 4691.4 4699.5 4746.8 4749.2 4760.3 5236.6 5262.4 5268.3 5285.5 5318.7 5450.7 5490.5 5698.9 5710.2 5715.0 5553.8 5612.1 5616.7 5621.3 5625.6 5207.0 5251.0 5251.8 5260.7 5262.1 5764.5 5765.2 5807.7 5812.3 5818.2 5357.0 5392.7 5398.7 5552.0 5553.8 5536.7 5543.6 5544.5 5545.0 5553.2 5510.7 5516.2 5520.6 5531.4 5602.2 5642.3 5666.7 5670.9 5676.5 5683.9 4202.8 4230.8 4354.6 4420.9 4427.8 3956.6 3981.1 3981.6 3985.9 3990.6 5471.4 5477.8 5480.6 5482.3 5485.9 5427.2 5438.5 5701.1 5741.1 5752.0 5281.1 5295.0 5295.4 5296.8 5299.1 4770.9 4777.5 4782.9 4845.9 4944.9 6045.2 6051.5 6054.3 6087.4 6100.3 5749.8 5751.4 5756.8 5758.5 5759.7 5694.9 5746.3 5748.9 6036.5 6044.2 5441.6 5444.2 5446.0 5450.1 5485.0 5494.7 5518.3 5647.3 5656.6 5763.5 5587.5 5588.2 5629.6 5631.8 5632.9 5338.9 5570.9 5577.1 5579.9 5584.3 5334.9 5440.7 5454.2 5455.5 5516.5 5643.7 5700.5 5704.8 5716.1 5727.9 5481.6 5505.0 5514.0 5557.3 5566.4 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | [#1] ext.html Cycles:180 Average:51489.75 Median:20637.80 stddev:91780.33 (444.7%) stddev-sans-first:91958.19 06:31:00 INFO - PROCESS | 30230 | Values: 575.0 587.8 595.7 600.3 601.0 30124.2 41108.0 43073.8 43162.3 46354.8 45594.0 46135.0 46149.3 46565.8 46580.0 9099.2 9277.1 9293.6 9297.8 9298.1 7451.0 7635.7 10574.7 10821.2 10872.2 10776.6 10780.6 10787.9 10816.1 10907.8 10639.9 10733.2 10743.5 10826.6 10882.7 10498.2 10571.4 10573.5 10574.6 10577.1 40350.0 40547.0 40582.8 40638.0 40835.4 3761.7 3773.9 3788.7 3800.2 3809.3 27787.6 30038.7 30997.8 32022.4 32667.5 3907.6 3924.3 3931.1 3958.6 4145.3 277909.0 280675.0 280854.0 281809.0 282381.0 49235.3 57834.1 57971.0 58493.7 58524.4 49030.0 59475.4 60918.7 61100.7 66551.0 14811.6 15358.9 15388.4 15401.4 15408.5 11766.2 11854.2 11874.8 11879.6 11880.5 14563.6 14876.6 16663.3 17015.5 17102.0 5947.2 5953.2 6024.1 6075.3 6111.8 891.3 901.8 902.3 907.3 926.1 29650.6 30190.8 30679.0 30937.4 30966.4 1004.9 1069.3 1077.7 1090.0 1109.9 15476.5 15747.6 15963.4 16489.2 16492.8 420301.0 420664.0 422566.0 423758.0 424299.0 59647.0 59747.4 59852.0 59855.7 59943.7 1337.2 1392.7 1393.5 1396.6 1398.5 28068.2 29399.3 29409.6 29750.0 30287.7 48956.5 49427.8 49460.8 49496.0 49557.0 327889.0 328567.0 329190.0 329389.0 329460.0 36819.4 37513.9 37980.8 38275.8 38425.4 36752.1 37784.8 43444.8 43851.8 45426.8 38385.4 39024.8 39027.2 40637.6 40841.8 38300.0 38386.0 39501.4 39542.4 40562.8 34708.5 35404.3 35579.0 37025.4 39420.0 28065.4 30241.0 31514.7 31561.3 33489.5 24928.4 25090.7 25196.4 25366.9 25639.7 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | [#2] jquery.html Cycles:180 Average:31797.71 Median:37681.12 stddev:100346.88 (266.3%) stddev-sans-first:100600.73 06:31:00 INFO - PROCESS | 30230 | Values: 426.3 426.9 432.7 437.6 447.5 17908.7 18946.2 19607.4 19770.3 19783.7 20101.2 20164.6 20627.6 20632.4 20913.8 5686.4 6001.0 6009.0 6013.8 6018.2 5761.4 5864.1 5870.2 5892.9 5965.5 5865.4 5866.9 5866.9 5877.4 5879.4 5709.1 5795.2 5805.2 5839.3 5841.9 5275.1 5298.0 5307.9 5310.8 5315.2 23018.7 23255.2 23308.9 23419.5 23543.0 2693.6 2728.1 2736.2 2741.5 2782.2 14472.1 14605.2 14664.6 14700.9 15260.9 1807.2 1856.4 1861.8 1896.9 1905.5 80076.0 80429.0 81191.6 81630.6 81864.6 11353.3 11423.9 11607.4 11749.9 11786.6 16144.1 16174.9 16227.3 16227.4 16247.4 7679.0 7852.8 7966.9 8076.6 8083.2 7237.7 7260.0 7267.4 7457.3 7474.2 7137.3 7263.7 7264.3 7314.7 7369.3 2020.2 2048.4 2049.2 2089.7 2110.6 852.9 856.6 871.2 873.7 874.5 17310.7 17507.8 17898.8 18267.6 18417.4 879.5 884.7 889.5 894.4 904.7 9928.3 10717.1 10807.8 10844.4 10874.2 603658.0 608505.0 613078.0 621552.0 624401.0 57579.7 70512.0 72602.3 76966.6 78298.6 1090.9 1116.2 1117.5 1129.6 1139.7 15898.8 16135.8 16507.2 16622.9 16806.2 37135.3 37473.6 37888.6 38002.2 38552.0 48180.0 49895.3 50052.7 50145.7 52496.5 8734.7 8743.4 8832.6 8925.7 9026.6 12755.7 12799.2 12890.9 12912.9 12923.3 11537.3 11606.6 11654.9 11693.0 12197.3 11966.1 12001.7 12160.5 12177.6 12189.6 12230.7 12285.6 12288.5 12456.7 12470.9 8731.2 8845.1 8899.7 9092.6 9263.4 11195.0 11209.7 11868.8 12031.2 12116.4 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | [#3] mootools.html Cycles:180 Average:5432.32 Median:5028.09 stddev:3045.14 (60.6%) stddev-sans-first:3037.47 06:31:00 INFO - PROCESS | 30230 | Values: 1251.3 1427.5 1432.5 1447.7 1460.3 7263.0 8199.3 8366.5 8470.3 8693.3 8547.1 8566.9 8582.7 8585.0 8631.1 1883.8 1953.8 2237.2 2274.3 2275.5 2196.1 2272.4 2275.2 2289.6 2297.9 2173.2 2195.5 2197.7 2199.6 2206.6 2223.0 2236.9 2237.1 2242.9 2243.6 2142.2 2171.3 2173.8 2185.3 2187.6 8158.1 8205.0 8207.3 8214.1 8249.6 4890.7 5015.3 5027.5 5028.6 5035.3 3078.6 3225.3 3232.4 3301.2 3301.9 1225.3 1334.1 1343.9 1388.8 1389.6 11340.8 11437.9 11438.7 11447.9 11456.9 4272.7 4464.2 4522.5 4546.0 4548.1 9764.4 9802.5 9812.0 9814.3 9814.3 5176.7 5178.7 5180.3 5180.4 5188.9 4677.0 4697.9 4712.1 4789.8 4805.4 3696.6 3763.1 3781.8 3838.3 3870.6 1227.6 1242.7 1249.2 1256.3 1257.1 855.4 873.8 880.1 899.0 901.5 5244.2 5256.6 5284.7 5340.8 5508.1 929.4 959.6 970.6 970.7 972.5 3427.4 3602.5 3744.7 3749.3 3764.1 9865.8 10118.0 10132.2 10143.6 10205.2 9940.8 10070.5 10071.2 10074.9 10095.2 7424.4 7622.0 7739.4 7882.7 8028.4 5307.4 5335.3 5367.4 5438.7 5577.4 9180.7 9276.0 9309.8 9309.9 9311.6 8211.3 8934.7 9278.8 9287.7 9427.5 6485.2 7098.9 7215.2 7290.3 7293.9 8132.9 8201.3 8255.8 8287.9 8317.5 7259.2 7346.6 7349.4 7411.4 7446.2 7247.9 7262.6 7268.5 7291.7 7359.4 6007.8 6019.9 6025.6 6066.0 6094.5 4674.6 4696.1 4751.0 4799.0 4827.8 4679.3 4724.7 4799.3 4847.4 4876.3 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | [#4] prototype.html Cycles:180 Average:4568.35 Median:5198.21 stddev:1315.38 (25.3%) stddev-sans-first:1281.42 06:31:00 INFO - PROCESS | 30230 | Values: 405.0 406.0 407.1 409.6 413.9 5183.7 5201.7 5228.1 5274.9 5291.0 4957.9 5066.7 5097.8 5177.6 5180.3 4088.6 4100.2 4100.6 4102.9 4123.2 4117.6 4131.2 4134.8 4158.7 4172.8 3791.0 3960.7 4152.8 4173.5 4182.0 3993.5 4009.9 4015.5 4015.9 4024.4 3581.2 3593.2 3601.1 3608.4 3617.3 5070.7 5111.2 5119.6 5131.3 5150.8 4485.3 4638.3 4651.5 4690.1 4691.2 5495.3 5499.5 5500.2 5509.9 5522.2 4017.8 4017.9 4048.6 4051.3 4052.8 5395.3 5477.3 5543.7 5656.9 5689.0 3946.2 3948.8 3953.4 3960.9 3964.5 4060.0 4084.0 4114.6 4123.1 4144.9 4538.4 4629.8 4630.5 4656.7 4672.7 5423.5 5446.5 5477.1 5482.5 5511.4 1356.3 1363.6 1373.9 1386.0 1391.8 933.6 971.8 973.6 977.6 988.3 5114.0 5166.9 5179.2 5194.8 5240.5 5373.8 5459.8 5498.1 5504.5 5524.3 4932.9 5117.2 5123.4 5139.4 5159.5 2861.7 2866.5 2872.4 2874.3 2898.3 5686.3 5696.4 5706.8 5730.7 5751.3 5223.4 5538.3 5540.2 5540.2 5561.6 5677.4 5684.1 5701.7 5709.1 5741.7 5215.7 5389.8 5438.3 5440.5 5455.4 5382.4 5385.5 5386.4 5396.2 5404.8 5521.2 5666.6 5721.5 5725.7 5742.5 4026.4 4026.9 4035.0 4052.3 4073.7 5512.9 5542.8 5547.7 5562.6 5578.6 5185.5 5420.7 5446.0 5446.8 5450.6 5392.4 5409.5 5421.6 5442.9 5452.6 5229.1 5359.9 5366.7 5417.6 5453.2 5379.8 5399.5 5416.5 5419.2 5425.4 5288.5 5292.0 5321.2 5325.9 5361.8 06:31:00 INFO - PROCESS | 30230 | 06:31:00 INFO - PROCESS | 30230 | [#5] yui.html Cycles:180 Average:16842.40 Median:40454.39 stddev:20340.68 (50.3%) stddev-sans-first:20361.71 06:31:00 INFO - PROCESS | 30230 | Values: 718.9 773.6 801.9 833.7 837.4 15049.2 15954.9 15958.1 15979.6 16009.8 14512.1 14616.4 14648.6 14667.1 14684.6 777.1 783.4 796.3 801.1 808.1 786.6 792.1 792.9 794.6 796.3 828.4 829.6 835.2 835.5 835.5 831.3 831.3 831.6 832.0 832.2 609.0 613.2 613.5 613.7 613.8 14861.1 15249.6 15317.8 15341.4 15349.1 14347.0 14419.9 14599.7 14950.4 14959.9 11961.2 12008.6 12146.5 12371.6 12637.7 3691.5 3703.4 3705.4 3783.5 3794.4 47599.0 49401.3 49514.8 65976.7 78267.0 11159.8 11797.4 11812.9 11847.9 12110.2 21727.9 21929.8 21933.9 22034.6 22110.3 8307.7 8422.7 8442.9 8527.7 8865.9 4308.1 4428.6 4428.8 4490.6 4502.9 3630.9 3653.7 3688.2 3739.3 3747.5 642.3 644.0 645.7 659.0 671.4 745.7 758.0 763.4 790.3 808.2 9599.3 10134.3 10206.9 10319.2 10473.0 754.8 765.5 789.2 792.4 794.7 4584.9 4764.0 5325.9 5545.2 5708.5 75741.6 76104.6 76779.6 79974.6 79991.2 64249.7 64461.7 64699.4 65060.7 65378.0 59346.0 60061.0 62505.7 63378.7 63853.4 9733.9 10286.3 10410.6 10412.5 10520.7 39373.2 39462.4 39772.6 41136.2 41244.6 47536.5 48686.0 49513.0 50457.2 52592.7 13017.7 13252.7 13484.1 13506.7 13522.7 13857.4 13953.7 14523.9 14802.0 14818.1 13881.8 14094.0 14713.6 15263.6 15412.8 13910.9 14408.9 14526.1 14890.2 15073.0 13539.7 14116.4 14553.2 14599.6 14755.5 11538.2 11672.9 11823.4 11980.1 12248.4 10806.9 11723.2 11972.6 12003.6 12004.8 06:31:00 INFO - PROCESS | 30230 | -------- Summary: end -------- 06:31:00 INFO - PROCESS | 30230 | 06:31:01 INFO - TEST-INFO | 30230: exit 0 06:31:01 INFO - TEST-OK | dromaeo_css | took 1117398ms 06:31:01 INFO - TEST-START | kraken 06:31:01 INFO - operating with platform_type : linux_ 06:31:01 INFO - Initialising browser for kraken test... 06:31:01 INFO - TEST-INFO | started process 30561 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3aLnGw/profile http://localhost:57818/getInfo.html) 06:31:04 INFO - PROCESS | 30561 | Iterating over profile /tmp/tmp3aLnGw/profile 06:31:05 INFO - PROCESS | 30561 | __metrics Screen width/height:1600/1200 06:31:05 INFO - PROCESS | 30561 | colorDepth:24 06:31:05 INFO - PROCESS | 30561 | Browser inner width/height: 1024/697 06:31:05 INFO - PROCESS | 30561 | __metrics 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/secmod.db = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/key3.db = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/prefs.js = Mon Feb 20 2017 06:11:42 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/compatibility.ini = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/mimeTypes.rdf = Mon Feb 20 2017 06:31:05 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/permissions.sqlite = Mon Feb 20 2017 06:31:02 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/gmp = Mon Feb 20 2017 06:31:02 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/cookies.sqlite = Mon Feb 20 2017 06:31:04 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/sessionCheckpoints.json = Mon Feb 20 2017 06:31:05 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/addons.json = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/directoryLinks.json = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/safebrowsing = Mon Feb 20 2017 06:31:05 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/content-prefs.sqlite = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/places.sqlite = Mon Feb 20 2017 06:31:05 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/crashes = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/pluginreg.dat = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | Stat failure 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/places.sqlite-shm = Mon Feb 20 2017 06:31:02 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/places.sqlite-wal = Mon Feb 20 2017 06:31:05 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/frequencyCap.json = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/bookmarkbackups = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/cache2 = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/minidumps = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/thumbnails = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/.parentlock = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/blocklist.xml = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/extensions.ini = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/user.js = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/cert8.db = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/startupCache = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/extensions = Mon Feb 20 2017 06:31:01 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/localstore.rdf = Mon Feb 20 2017 06:11:42 GMT-0800 (PST) 06:31:05 INFO - PROCESS | 30561 | No birth date. Using mtime. 06:31:05 INFO - PROCESS | 30561 | Using date: /tmp/tmp3aLnGw/profile/extensions.json = Mon Feb 20 2017 06:31:03 GMT-0800 (PST) 06:31:06 INFO - PROCESS | 30561 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:31:06 INFO - PROCESS | 30561 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:31:07 INFO - TEST-INFO | 30561: exit 0 06:31:07 INFO - Browser initialized. 06:31:07 INFO - Running cycle 1/1 for kraken test... 06:31:07 INFO - TEST-INFO | started process 30657 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3aLnGw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:31:19 INFO - PROCESS | 30657 | RSS: Main: 142712832 06:31:19 INFO - PROCESS | 30657 | 06:31:21 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57818/tests/kraken/driver.html?testName=audio-beat-detection) 06:31:22 INFO - PROCESS | 30657 | RSS: Main: 197935104 06:31:22 INFO - PROCESS | 30657 | 06:31:25 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57818/tests/kraken/driver.html?testName=audio-dft) 06:31:26 INFO - PROCESS | 30657 | RSS: Main: 177610752 06:31:26 INFO - PROCESS | 30657 | 06:31:28 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57818/tests/kraken/driver.html?testName=audio-fft) 06:31:29 INFO - PROCESS | 30657 | RSS: Main: 258523136 06:31:29 INFO - PROCESS | 30657 | 06:31:31 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57818/tests/kraken/driver.html?testName=audio-oscillator) 06:31:32 INFO - PROCESS | 30657 | RSS: Main: 170725376 06:31:32 INFO - PROCESS | 30657 | 06:31:34 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57818/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:31:34 INFO - PROCESS | 30657 | RSS: Main: 174055424 06:31:34 INFO - PROCESS | 30657 | 06:31:37 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57818/tests/kraken/driver.html?testName=imaging-darkroom) 06:31:38 INFO - PROCESS | 30657 | RSS: Main: 213905408 06:31:38 INFO - PROCESS | 30657 | 06:31:41 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57818/tests/kraken/driver.html?testName=imaging-desaturate) 06:31:42 INFO - PROCESS | 30657 | RSS: Main: 214044672 06:31:42 INFO - PROCESS | 30657 | 06:31:45 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57818/tests/kraken/driver.html?testName=json-parse-financial) 06:31:45 INFO - PROCESS | 30657 | RSS: Main: 212283392 06:31:45 INFO - PROCESS | 30657 | 06:31:47 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57818/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:31:47 INFO - PROCESS | 30657 | RSS: Main: 174039040 06:31:47 INFO - PROCESS | 30657 | 06:31:49 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:31:50 INFO - PROCESS | 30657 | RSS: Main: 181243904 06:31:50 INFO - PROCESS | 30657 | 06:31:52 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:31:53 INFO - PROCESS | 30657 | RSS: Main: 190230528 06:31:53 INFO - PROCESS | 30657 | 06:31:55 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:31:56 INFO - PROCESS | 30657 | RSS: Main: 199831552 06:31:56 INFO - PROCESS | 30657 | 06:31:59 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:32:00 INFO - PROCESS | 30657 | RSS: Main: 195907584 06:32:00 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | Cycle 1(1): loaded http://localhost:57818/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57818/tests/kraken/driver.html?testName=ai-astar) 06:32:01 INFO - PROCESS | 30657 | RSS: Main: 247762944 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | __start_tp_report 06:32:01 INFO - PROCESS | 30657 | _x_x_mozilla_page_load 06:32:01 INFO - PROCESS | 30657 | _x_x_mozilla_page_load_details 06:32:01 INFO - PROCESS | 30657 | |i|pagename|runs| 06:32:01 INFO - PROCESS | 30657 | |0;ai-astar;111;112;113;112;112;113;113;112;112;111 06:32:01 INFO - PROCESS | 30657 | |1;audio-beat-detection;148;121;122;166;123;121;121;121;142;121 06:32:01 INFO - PROCESS | 30657 | |2;audio-dft;164;161;162;163;162;163;164;165;162;162 06:32:01 INFO - PROCESS | 30657 | |3;audio-fft;75;75;75;107;79;76;76;76;93;76 06:32:01 INFO - PROCESS | 30657 | |4;audio-oscillator;83;82;104;82;83;84;94;82;83;109 06:32:01 INFO - PROCESS | 30657 | |5;imaging-gaussian-blur;114;113;113;113;113;113;113;113;112;113 06:32:01 INFO - PROCESS | 30657 | |6;imaging-darkroom;147;147;148;148;146;147;149;147;147;148 06:32:01 INFO - PROCESS | 30657 | |7;imaging-desaturate;96;95;95;95;95;96;96;96;96;96 06:32:01 INFO - PROCESS | 30657 | |8;json-parse-financial;55;55;54;55;55;63;56;54;54;55 06:32:01 INFO - PROCESS | 30657 | |9;json-stringify-tinderbox;64;64;64;63;64;64;64;64;64;64 06:32:01 INFO - PROCESS | 30657 | |10;stanford-crypto-aes;85;95;84;94;90;84;86;85;84;84 06:32:01 INFO - PROCESS | 30657 | |11;stanford-crypto-ccm;132;141;134;142;142;131;135;129;182;147 06:32:01 INFO - PROCESS | 30657 | |12;stanford-crypto-pbkdf2;176;176;178;179;173;178;176;172;182;182 06:32:01 INFO - PROCESS | 30657 | |13;stanford-crypto-sha256-iterative;64;65;64;64;65;76;69;68;64;62 06:32:01 INFO - PROCESS | 30657 | __end_tp_report 06:32:01 INFO - PROCESS | 30657 | __start_cc_report 06:32:01 INFO - PROCESS | 30657 | _x_x_mozilla_cycle_collect,1729 06:32:01 INFO - PROCESS | 30657 | __end_cc_report 06:32:01 INFO - PROCESS | 30657 | __startTimestamp1487601121922__endTimestamp 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | ------- Summary: start ------- 06:32:01 INFO - PROCESS | 30657 | Number of tests: 14 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:0.74 (0.7%) stddev-sans-first:0.67 06:32:01 INFO - PROCESS | 30657 | Values: 111.0 112.0 113.0 112.0 112.0 113.0 113.0 112.0 112.0 111.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:122.50 stddev:15.91 (13.0%) stddev-sans-first:15.58 06:32:01 INFO - PROCESS | 30657 | Values: 148.0 121.0 122.0 166.0 123.0 121.0 121.0 121.0 142.0 121.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.23 (0.8%) stddev-sans-first:1.22 06:32:01 INFO - PROCESS | 30657 | Values: 164.0 161.0 162.0 163.0 162.0 163.0 164.0 165.0 162.0 162.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:10.71 (14.1%) stddev-sans-first:11.15 06:32:01 INFO - PROCESS | 30657 | Values: 75.0 75.0 75.0 107.0 79.0 76.0 76.0 76.0 93.0 76.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:83.00 stddev:10.16 (12.2%) stddev-sans-first:10.57 06:32:01 INFO - PROCESS | 30657 | Values: 83.0 82.0 104.0 82.0 83.0 84.0 94.0 82.0 83.0 109.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 06:32:01 INFO - PROCESS | 30657 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 06:32:01 INFO - PROCESS | 30657 | Values: 147.0 147.0 148.0 148.0 146.0 147.0 149.0 147.0 147.0 148.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 06:32:01 INFO - PROCESS | 30657 | Values: 96.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.00 stddev:2.67 (4.9%) stddev-sans-first:2.83 06:32:01 INFO - PROCESS | 30657 | Values: 55.0 55.0 54.0 55.0 55.0 63.0 56.0 54.0 54.0 55.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 06:32:01 INFO - PROCESS | 30657 | Values: 64.0 64.0 64.0 63.0 64.0 64.0 64.0 64.0 64.0 64.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:85.50 stddev:4.31 (5.0%) stddev-sans-first:4.50 06:32:01 INFO - PROCESS | 30657 | Values: 85.0 95.0 84.0 94.0 90.0 84.0 86.0 85.0 84.0 84.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:141.50 stddev:15.37 (10.9%) stddev-sans-first:15.91 06:32:01 INFO - PROCESS | 30657 | Values: 132.0 141.0 134.0 142.0 142.0 131.0 135.0 129.0 182.0 147.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:178.00 stddev:3.33 (1.9%) stddev-sans-first:3.50 06:32:01 INFO - PROCESS | 30657 | Values: 176.0 176.0 178.0 179.0 173.0 178.0 176.0 172.0 182.0 182.0 06:32:01 INFO - PROCESS | 30657 | 06:32:01 INFO - PROCESS | 30657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:65.00 stddev:4.04 (6.2%) stddev-sans-first:4.21 06:32:01 INFO - PROCESS | 30657 | Values: 64.0 65.0 64.0 64.0 65.0 76.0 69.0 68.0 64.0 62.0 06:32:01 INFO - PROCESS | 30657 | -------- Summary: end -------- 06:32:01 INFO - PROCESS | 30657 | 06:32:03 INFO - TEST-INFO | 30657: exit 0 06:32:03 INFO - TEST-OK | kraken | took 61560ms 06:32:03 INFO - SUITE-END | took 1178s 06:32:03 INFO - Completed test suite (00:19:38) 06:32:03 INFO - javascript benchmark 06:32:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7084.344304406414, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.2681469854583, 3143.2613335866067, 3149.511825667021, 3167.572377729006, 3196.920076998075, 5039.924401133986, 5113.565346945514, 5135.281060651511, 5178.0938335791225, 5230.293910322105, 5219.45195754446, 5228.215767634857, 5250.343707036621, 5262.05283049051, 5279.815206467765, 4594.74728889911, 4606.97696511517, 4612.492625811166, 4780.737059461728, 4819.277108433728, 4805.447373552051, 4818.9036219275595, 4861.416630004404, 4862.951370486286, 4875.171220892456, 4564.931526027096, 4599.195140850371, 4604.148232576984, 4664.323673067386, 4850.53919877611, 4623.884402889921, 4627.3521706961055, 4641.419822522184, 4660.5339466053465, 4703.905921881543, 4691.413573303337, 4699.483056863743, 4746.83386081489, 4749.24012158053, 4760.262159365304, 5236.607254455931, 5262.447443018466, 5268.262443257909, 5285.524302812727, 5318.707471089088, 5450.672959622435, 5490.505854473076, 5698.914516282259, 5710.229119068914, 5715.0, 5553.805616803393, 5612.102063669834, 5616.747246373923, 5621.269234999465, 5625.578081643893, 5206.973965130188, 5250.976059668366, 5251.789928402859, 5260.736963151827, 5262.079136151158, 5764.481196692318, 5765.164051212634, 5807.709614519342, 5812.3025236971835, 5818.21454103699, 5357.0, 5392.730363481889, 5398.676079435247, 5551.9722401387735, 5553.77784888608, 5536.7231638418725, 5543.61194716366, 5544.500994910478, 5545.0, 5553.194786755897, 5510.669359838343, 5516.172574113834, 5520.558355331564, 5531.446855314517, 5602.215689803431, 5642.266505354278, 5666.744996475169, 5670.886582268414, 5676.489115979499, 5683.914741278884, 4202.768847713405, 4230.830766769356, 4354.586314300136, 4420.9115817684105, 4427.756473393994, 3956.604339566078, 3981.064449854282, 3981.6018398159954, 3985.9202815943518, 3990.5809889961715, 5471.425500322488, 5477.835664930059, 5480.561555075585, 5482.2873026506295, 5485.945140548623, 5427.185922111636, 5438.510534051954, 5701.144828275709, 5741.1388291756575, 5752.022156233366, 5281.12861377876, 5295.046891559557, 5295.417504074549, 5296.761645725875, 5299.072662284085, 4770.904581908342, 4777.522247775195, 4782.856514304576, 4845.927311090337, 4944.851654450372, 6045.214122164267, 6051.546134039964, 6054.3340232575265, 6087.36082711309, 6100.298465676416, 5749.798757043483, 5751.424857514216, 5756.798512052058, 5758.510526605118, 5759.65442073477, 5694.886102278014, 5746.281714785652, 5748.9425105748405, 6036.547258955508, 6044.24446944132, 5441.646292990943, 5444.183372494158, 5446.0, 5450.073487507054, 5485.040117979417, 5494.697791621499, 5518.255035570147, 5647.265855438931, 5656.595946722544, 5763.452472015152, 5587.525060369914, 5588.245586845724, 5629.634073785354, 5631.831045068655, 5632.85917852057, 5338.946610533845, 5570.916436253378, 5577.079781836114, 5579.944200558105, 5584.329880414376, 5334.946650533445, 5440.727963601725, 5454.236406902957, 5455.509004189642, 5516.531094856982, 5643.71781410936, 5700.543956483554, 5704.80033198836, 5716.056850619601, 5727.942720572741, 5481.561475082063, 5504.972475137599, 5514.035043867388, 5557.305336832896, 5566.443355664564], "value": 5200.452631226308, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.9741261643226, 587.7737071227579, 595.6515438468497, 600.3486217454063, 601.0173366545697, 30124.246893827636, 41108.0, 43073.78463107684, 43162.25215226322, 46354.76822615891, 45594.0, 46135.0, 46149.30776038362, 46565.767171164094, 46580.0, 9099.181073703365, 9277.118673725985, 9293.628254869796, 9297.767555811124, 9298.116678915492, 7450.962745186266, 7635.732749353774, 10574.682759517227, 10821.242513024092, 10872.18458615603, 10776.622818201364, 10780.622678206264, 10787.946060269727, 10816.134709223243, 10907.83638245427, 10639.946800265989, 10733.248672592921, 10743.462826858626, 10826.621068262652, 10882.67351979434, 10498.160147188206, 10571.418571978538, 10573.524191411405, 10574.577016919315, 10577.100946419563, 40350.0, 40547.0, 40582.79708601438, 40638.0, 40835.38746918798, 3761.6614504694708, 3773.8867833965023, 3788.7347885648014, 3800.22092003784, 3809.276237514863, 27787.58318625222, 30038.69961300381, 30997.845010774803, 32022.35955280881, 32667.509987350208, 3907.628775266345, 3924.2936271471126, 3931.1351502669654, 3958.6041395860184, 4145.2952997990415, 277909.0, 280675.0, 280854.0, 281809.0, 282381.0, 49235.26147107796, 57834.132488012714, 57971.0, 58493.70753146207, 58524.414755851896, 49030.0, 59475.40524594699, 60918.69540652268, 61100.694496527234, 66551.0, 14811.629709257139, 15358.923205384124, 15388.38446462129, 15401.383944642314, 15408.537743867702, 11766.235194712317, 11854.170208085352, 11874.821877671842, 11879.643610691694, 11880.524779008916, 14563.563093107225, 14876.628084297763, 16663.3334666612, 17015.489535313962, 17102.0, 5947.167396564484, 5953.196318496947, 6024.126501657235, 6075.33171351151, 6111.755529778759, 891.31814162166, 901.756525738047, 902.2691619787993, 907.2560500389617, 926.064674678566, 29650.594094310436, 30190.849045754632, 30679.0, 30937.381252375275, 30966.380672386425, 1004.924630652704, 1069.2889228663007, 1077.6982444915436, 1089.9509522071367, 1109.938953357557, 15476.458323958608, 15747.606309842116, 15963.36146554148, 16489.175541222892, 16492.752608710878, 420301.0, 420664.0, 422566.0, 423758.0, 424299.0, 59647.0, 59747.402525975056, 59852.0, 59855.70072149611, 59943.70028149919, 1337.164272329794, 1392.7075314183933, 1393.540131756518, 1396.6438558167806, 1398.4965412451509, 28068.157955261377, 29399.26501837471, 29409.55885661674, 29750.0, 30287.697123029368, 48956.51043489519, 49427.752861235465, 49460.75269623629, 49496.0, 49557.0, 327889.0, 328567.0, 329190.0, 329389.0, 329460.0, 36819.447708283864, 37513.93450408176, 37980.810095949346, 38275.80862095672, 38425.42361864518, 36752.08119797026, 37784.811075944446, 43444.782776085915, 43851.78074109737, 45426.772866135456, 38385.4242186373, 39024.80487597544, 39027.219455611295, 40637.59362406338, 40841.79579102086, 38300.0, 38386.0403489915, 39501.40747888727, 39542.40686389649, 40562.79718601388, 34708.47937280892, 35404.29191416209, 35579.0, 37025.44461833128, 39420.0, 28065.43869122647, 30241.0, 31514.684853151175, 31561.301969419434, 33489.49765753564, 24928.37679058038, 25090.74909250957, 25196.370090747878, 25366.873165634053, 25639.743602563736], "value": 19336.751742773184, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.34981652979224, 426.93274593611557, 432.66468486922633, 437.6258299154223, 447.47198305998927, 17908.731369029458, 18946.24215031397, 19607.41177764666, 19770.30803921863, 19783.703244451328, 20101.195952161932, 20164.59670806583, 20627.587448251026, 20632.38102856913, 20913.790862091373, 5686.374498805118, 6001.0, 6008.969955150218, 6013.849653758669, 6018.15745795589, 5761.423857614226, 5864.091065884794, 5870.178175055496, 5892.852678683046, 5965.522758179357, 5865.35481097079, 5866.853328666774, 5866.88266234675, 5877.353491115995, 5879.382664820197, 5709.143628455723, 5795.2176456178295, 5805.245318108661, 5839.328477225131, 5841.88316233673, 5275.050490911634, 5298.0, 5307.893842123174, 5310.7610157543, 5315.176147697074, 23018.66229396745, 23255.18606848752, 23308.883455582614, 23419.531609367717, 23543.0, 2693.6498255226693, 2728.1269993602054, 2736.165469531792, 2741.520233959049, 2782.248792825946, 14472.131672099707, 14605.196714180716, 14664.560063198014, 14700.926495367561, 15260.923695381563, 1807.1777341309673, 1856.4152292027968, 1861.795202527721, 1896.8861868287809, 1905.5426697592616, 80076.0, 80429.0, 81191.59404203, 81630.59184704038, 81864.59067704623, 11353.318800871974, 11423.942880285545, 11607.36159511235, 11749.88250117505, 11786.646400607824, 16144.112073836051, 16174.926763914818, 16227.269772860249, 16227.350905963865, 16247.350105995863, 7678.96160519205, 7852.764417067383, 7966.8572342382795, 8076.55578943152, 8083.151269116658, 7237.710491580277, 7259.963700181571, 7267.38227250679, 7457.291557302047, 7474.177840437547, 7137.321954414322, 7263.709451621981, 7264.309890560494, 7314.719924013383, 7369.299916507923, 2020.202020202012, 2048.354768247997, 2049.2008116834445, 2089.7178880851193, 2110.641190997534, 852.8976522817178, 856.5503110866845, 871.1549796697194, 873.6680061576687, 874.4927941793744, 17310.740338894928, 17507.82492175062, 17898.821011789714, 18267.63464730698, 18417.421560981475, 879.4679219072497, 884.7478468636408, 889.479654402182, 894.3784070071238, 904.7059705595708, 9928.305018648769, 10717.142628589694, 10807.83788243177, 10844.403557804397, 10874.184436167161, 603658.0, 608505.0, 613078.0, 621552.0, 624401.0, 57579.712101439225, 70512.0, 72602.27397726061, 76966.6151669238, 78298.6085069571, 1090.8527348807809, 1116.1740312168936, 1117.541807858774, 1129.5651174297955, 1139.6865861888048, 15898.841011590199, 16135.75796363032, 16507.174641268128, 16622.916885415496, 16806.243719032427, 37135.25729485449, 37473.6252637481, 37888.62111378961, 38002.23995520019, 38552.0, 48180.0, 49895.259950304666, 50052.749736251084, 50145.74927125341, 52496.47503524916, 8734.69428569997, 8743.38796284254, 8832.602532885907, 8925.687600933936, 9026.638934442548, 12755.681107972376, 12799.168054076456, 12890.935545322214, 12912.870871291543, 12923.28921909304, 11537.307761534335, 11606.593769218036, 11654.883451165719, 11693.0, 12197.329146897006, 11966.12702366127, 12001.699957500783, 12160.513579457076, 12177.573784917486, 12189.634310970685, 12230.694232643898, 12285.570005050138, 12288.508459661514, 12456.688582785502, 12470.875291247334, 8731.170538798804, 8845.115488451103, 8899.688510902088, 9092.636294548409, 9263.351565390358, 11195.0, 11209.71975700614, 11868.82196767032, 12031.217970831867, 12116.394180291127], "value": 9422.654471722484, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1251.349298364851, 1427.5360507834953, 1432.5057855040006, 1447.6525633847873, 1460.2917584971294, 7263.0, 8199.262066414021, 8366.53984030878, 8470.322374210064, 8693.261072808804, 8547.10255423181, 8566.871496927552, 8582.742517724462, 8585.0, 8631.136886311382, 1883.7739471263399, 1953.794851540589, 2237.2169740590825, 2274.3404412720374, 2275.4538910661486, 2196.088623221361, 2272.3750968483632, 2275.2150508074687, 2289.5535370602656, 2297.9195728149516, 2173.1741938063515, 2195.549912267981, 2197.6923230747707, 2199.571083638683, 2206.569718904814, 2222.9777702223014, 2236.9217077402213, 2237.149883044449, 2242.921497747587, 2243.562505311457, 2142.2180903970093, 2171.3160354488286, 2173.826093912499, 2185.2570126157184, 2187.55155193185, 8158.102608713037, 8205.0, 8207.261346478846, 8214.096449390563, 8249.587520623945, 4890.73100979446, 5015.32293140425, 5027.522385373383, 5028.647994640377, 5035.2628343221895, 3078.6305643322944, 3225.2743132795167, 3232.402005628943, 3301.207710149547, 3301.933961320784, 1225.3138242584093, 1334.0661536924192, 1343.8992075594272, 1388.7708528092853, 1389.6456403617115, 11340.829887551694, 11437.942810285895, 11438.714032149179, 11447.942760286229, 11456.885431145665, 4272.679549033773, 4464.2187617166865, 4522.479914809774, 4545.977270113628, 4548.113117942035, 9764.414135151772, 9802.460864652512, 9812.0, 9814.263930205263, 9814.312998090065, 5176.7152806596, 5178.741062946838, 5180.274761533388, 5180.404253510819, 5188.870278243074, 4677.017826256456, 4697.906041879211, 4712.057588482317, 4789.760511974388, 4805.37530121082, 3696.5748938871834, 3763.1344790698295, 3781.829817658212, 3838.2899163654815, 3870.5548861881243, 1227.551943540619, 1242.7141757395668, 1249.194269696041, 1256.296473974577, 1257.0509265504486, 855.4268640011207, 873.7990262239721, 880.0847118996301, 898.9595468203946, 901.5176880368971, 5244.164542410203, 5256.579473642099, 5284.735763211824, 5340.786368545215, 5508.14623733322, 929.4469790474657, 959.5921733263475, 970.6360114956891, 970.669972209438, 972.5234635028927, 3427.3830710471857, 3602.531670882821, 3744.6629803317833, 3749.3063783200196, 3764.0966168120003, 9865.753356166153, 10118.0, 10132.189424845994, 10143.594256229815, 10205.183585313158, 9940.751481212883, 10070.496475176358, 10071.194304455626, 10074.899251007542, 10095.24285678577, 7424.368928641126, 7621.961890190624, 7739.419543534257, 7882.7241046563095, 8028.43800933929, 5307.4427185145005, 5335.279737235423, 5367.382750983531, 5438.700871452108, 5577.44225577439, 9180.724578262663, 9275.953620231856, 9309.813803724022, 9309.906900930904, 9311.627534898527, 8211.302039326723, 8934.73195804127, 9278.768030799014, 9287.67493137764, 9427.528623568656, 6485.189351331083, 7098.893516597358, 7215.2063273038875, 7290.307420795016, 7293.927060729537, 8132.878006829784, 8201.343892488705, 8255.834883302181, 8287.875681864656, 8317.459365141247, 7259.165196002316, 7346.6326683666675, 7349.375303099297, 7411.370033547209, 7446.218147094693, 7247.8550428990075, 7262.636868156676, 7268.454865884974, 7291.671874765542, 7359.411247100326, 6007.849803754941, 6019.909701354571, 6025.58398776296, 6065.969670151621, 6094.51243900478, 4674.579287864109, 4696.131215725101, 4751.0, 4798.992211635596, 4827.782749776198, 4679.344891715231, 4724.6692731508465, 4799.3041009052795, 4847.3698419206685, 4876.317315575751], "value": 4387.398342733682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.0, 405.9594040595941, 407.1328071208326, 409.5699515508715, 413.93583994480866, 5183.663061900978, 5201.661891977019, 5228.137357336044, 5274.920876186856, 5290.973545132279, 4957.925631115527, 5066.695998240109, 5097.796088156479, 5177.637565370425, 5180.274761533388, 4088.6115818997146, 4100.200460910124, 4100.626290192789, 4102.856400025999, 4123.216588848124, 4117.56765539617, 4131.194417088669, 4134.842244171637, 4158.688098392618, 4172.791360431981, 3790.9810450947707, 3960.7423768451995, 4152.750834949898, 4173.455821346091, 4182.0, 3993.4808474898223, 4009.859654912064, 4015.538213105501, 4015.9397609035595, 4024.416459613369, 3581.194231297963, 3593.1735700788704, 3601.135727425399, 3608.350496910555, 3617.3488772021024, 5070.670406423571, 5111.2333150027425, 5119.59043276537, 5131.3329267195395, 5150.845474635767, 4485.304777759453, 4638.304254361839, 4651.465081515601, 4690.132325519756, 4691.249400096002, 5495.2856128703415, 5499.477549632778, 5500.202470641774, 5509.889802203974, 5522.171674248895, 4017.799110044486, 4017.859374921893, 4048.6356227939627, 4051.3315302974975, 4052.837886484537, 5395.29861118056, 5477.315335583052, 5543.695096769676, 5656.878670498563, 5688.971555142198, 3946.2304850553865, 3948.8153553933703, 3953.446517487554, 3960.881173564798, 3964.5044369453817, 4059.9797001014804, 4084.0402505411203, 4114.6091121343425, 4123.0516981094515, 4144.896377590584, 4538.387317712132, 4629.837955671535, 4630.513796051433, 4656.743879086615, 4672.672912896132, 5423.511883930386, 5446.5414461816235, 5477.096279113981, 5482.533984611273, 5511.3937466878615, 1356.2608378433777, 1363.6431750408408, 1373.9175649461065, 1385.9930700346433, 1391.7773156294945, 933.6498812945146, 971.7959228562081, 973.5667627905656, 977.5894124467741, 988.2933702402817, 5114.028334616411, 5166.896662066738, 5179.2291124249095, 5194.766250512241, 5240.4759524047295, 5373.844623405908, 5459.781608735686, 5498.120300751861, 5504.4770746778995, 5524.3370795504, 4932.926006109911, 5117.232415137684, 5123.436421993578, 5139.383274007143, 5159.535641792181, 2861.7138286171635, 2866.4840328741025, 2872.3824377758883, 2874.2754347391397, 2898.347871728844, 5686.289213848269, 5696.401877802854, 5706.800261990894, 5730.7421166047625, 5751.281089863767, 5223.425423203445, 5538.252335934597, 5540.2243685884005, 5540.2243685884005, 5561.63849349791, 5677.37548869624, 5684.072702558268, 5701.657900525982, 5709.115087161496, 5741.712914354349, 5215.687058776485, 5389.8113566025, 5438.320209973754, 5440.5038614381365, 5455.399906010256, 5382.381026182039, 5385.492062222584, 5386.353637563518, 5396.192275587722, 5404.783923617155, 5521.199426083276, 5666.640006398352, 5721.485066344049, 5725.7137143141845, 5742.511886489695, 4026.355783074694, 4026.91946161081, 4035.0, 4052.311107111857, 4073.7371414861486, 5512.897420515993, 5542.8337149883955, 5547.667139971614, 5562.638428502296, 5578.609497335147, 5185.536869986726, 5420.701861397661, 5445.972770136283, 5446.836594902159, 5450.5912056595125, 5392.352917649907, 5409.456056912212, 5421.62048656606, 5442.8639284016635, 5452.591055670761, 5229.13719236337, 5359.892802144013, 5366.7191443637075, 5417.566594672496, 5453.2365468833605, 5379.757910894097, 5399.541039011728, 5416.51251387377, 5419.187121931742, 5425.430329815313, 5288.497592728685, 5291.973540132275, 5321.243989483539, 5325.881564871421, 5361.785528578813], "value": 4171.978010308898, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.8526352097821, 773.6479901644752, 801.8797180422936, 833.7373727275909, 837.401258100458, 15049.172295523742, 15954.920225398857, 15958.122303273316, 15979.600509987256, 16009.759853602176, 14512.129272243646, 14616.415343386252, 14648.560543183721, 14667.119972801613, 14684.632884177874, 777.1179711027968, 783.4163548156631, 796.3350602247131, 801.0947629179049, 808.1352952340998, 786.6224212378062, 792.1247022040649, 792.8850316704074, 794.5868148562744, 796.34699546372, 828.3952714518405, 829.6349606173295, 835.1982097186677, 835.5362773660615, 835.5404527509876, 831.264331067007, 831.2726364431123, 831.5592735850009, 831.983360332796, 832.2052439919862, 609.0468416927495, 613.1875265273486, 613.4785432382483, 613.6594190224424, 613.7943788830747, 14861.108333500024, 15249.6187595311, 15317.846821531864, 15341.386344546205, 15349.07905525672, 14347.0, 14419.927900360535, 14599.70800583993, 14950.40198392063, 14959.850401496064, 11961.162718609701, 12008.63974080779, 12146.514139434412, 12371.566995155126, 12637.684057898534, 3691.53855768029, 3703.3519134151684, 3705.3886108792285, 3783.451399547078, 3794.3739283018267, 47599.0, 49401.25898111531, 49514.752426237996, 65976.67011664959, 78267.0, 11159.776804463865, 11797.410129493492, 11812.88187118135, 11847.881521184678, 12110.152289339661, 21727.891360543414, 21929.780702192773, 21933.890330548566, 22034.559308813736, 22110.33668989933, 8307.709230176915, 8422.747317580483, 8442.915570844336, 8527.658893644184, 8865.911340886638, 4308.095299987038, 4428.557144285545, 4428.82284708608, 4490.640748740093, 4502.887427814331, 3630.9092272692865, 3653.6711695947492, 3688.207035487383, 3739.2521495700967, 3747.45661879026, 642.3095172689377, 643.9967800161063, 645.6803882078401, 659.0378048049901, 671.385682100882, 745.7464462082918, 757.9810504737426, 763.3625922354896, 790.2768966395683, 808.1877712898499, 9599.32804703664, 10134.341267817716, 10206.897931020594, 10319.1744660427, 10473.0, 754.8150703077781, 765.5062484697339, 789.2265579731895, 792.4373694676729, 794.7059587952378, 4584.88537786558, 4763.976180119077, 5325.868218135454, 5545.168224766237, 5708.457696518907, 75741.62129189319, 76104.61947690225, 76779.61610191913, 79974.60012700016, 79991.20008799953, 64249.67875160594, 64461.67769161218, 64699.35300646933, 65060.674696626214, 65378.0, 59346.0, 60061.0, 62505.68747156235, 63378.732425351234, 63853.36146638474, 9733.902660973583, 10286.331388459726, 10410.575505410321, 10412.531436085546, 10520.736981575521, 39373.21253574855, 39462.40806387964, 39772.60227397689, 41136.1772764549, 41244.587554124075, 47536.52463475321, 48686.0, 49513.0, 50457.24314135365, 52592.737036314575, 13017.674558135743, 13252.734945301232, 13484.12353197039, 13506.662333441742, 13522.72954540884, 13857.37641806101, 13953.720925581634, 14523.927380363031, 14802.0, 14818.110913345234, 13881.79177312321, 14094.0, 14713.55859324222, 15263.618409539851, 15412.768808468107, 13910.930445348113, 14408.927955360155, 14526.128432294096, 14890.181040042467, 15073.0, 13539.661508462368, 14116.435342586181, 14553.212130811626, 14599.635009124431, 14755.483558075415, 11538.192326537062, 11672.88327116718, 11823.408829558659, 11980.10149238828, 12248.448819802952, 10806.891931080589, 11723.179377443837, 11972.58095966637, 12003.579874704343, 12004.819927701266], "value": 7287.927562377503, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0, 112.0, 111.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 121.0, 122.0, 166.0, 123.0, 121.0, 121.0, 121.0, 142.0, 121.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 163.0, 162.0, 163.0, 164.0, 165.0, 162.0, 162.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 107.0, 79.0, 76.0, 76.0, 76.0, 93.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 104.0, 82.0, 83.0, 84.0, 94.0, 82.0, 83.0, 109.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 146.0, 147.0, 149.0, 147.0, 147.0, 148.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 55.0, 63.0, 56.0, 54.0, 54.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 95.0, 84.0, 94.0, 90.0, 84.0, 86.0, 85.0, 84.0, 84.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 141.0, 134.0, 142.0, 142.0, 131.0, 135.0, 129.0, 182.0, 147.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 178.0, 179.0, 173.0, 178.0, 176.0, 172.0, 182.0, 182.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 76.0, 69.0, 68.0, 64.0, 62.0], "value": 66.1, "unit": "score"}]}]} 06:32:03 INFO - Return code: 0 06:32:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:32:03 ERROR - # TBPL SUCCESS # 06:32:03 INFO - Running post-action listener: _resource_record_post_action 06:32:03 INFO - [mozharness: 2017-02-20 14:32:03.235279Z] Finished run-tests step (success) 06:32:03 INFO - Running post-run listener: _resource_record_post_run 06:32:03 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 364027904; Read time: 12; Write time: 191276 06:32:03 INFO - TinderboxPrint: CPU usage
13.1% 06:32:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 06:32:03 INFO - TinderboxPrint: I/O write bytes / time
364,027,904 / 191,276 06:32:03 INFO - TinderboxPrint: CPU idle
8,294.5 (86.9%) 06:32:03 INFO - TinderboxPrint: CPU user
1,204.3 (12.6%) 06:32:03 INFO - TinderboxPrint: Swap in / out
0 / 0 06:32:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110080000; Read time: 0; Write time: 64104 06:32:03 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 252862464; Read time: 12; Write time: 127120 06:32:03 INFO - Running post-run listener: _upload_blobber_files 06:32:03 INFO - Blob upload gear active. 06:32:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:32:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:32:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:32:04 INFO - (blobuploader) - INFO - Open directory for files ... 06:32:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:32:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:32:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:32:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:05 INFO - (blobuploader) - INFO - Done attempting. 06:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:06 INFO - (blobuploader) - INFO - Done attempting. 06:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:09 INFO - (blobuploader) - INFO - Done attempting. 06:32:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:32:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:32:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:09 INFO - (blobuploader) - INFO - Done attempting. 06:32:09 INFO - (blobuploader) - INFO - Iteration through files over. 06:32:09 INFO - Return code: 0 06:32:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:32:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:32:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a27efdc93d2ee89e3bc28a9cc56ddbb83dae07d031068f8049f986e5bb32f8912cda90173975ec21a241cb676d004f2e676075b52251875a9151f71e465c1f1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9886468dbd9554426e924bbacf8e4531695d134d7262c243ebd5a1487012fa40dff956bc7f4ef6b25b81916a4869848a3d459657831833a22fb6a0fbc3e3eef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2924da5a033f2d83e5691d9b4a5592ac65fb28d8a20b58ce6ed4adbb5b294341293fca69baf23b1c92dd93f88a57dfd5d82bd0aa113567edb9292bd7b32dcda6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a4dec548a70d6b9ed5ee3422512cf9ca001e1270df5b0bf92e99f35c42e7c3b72720bce04bd3022c717aab00fe3acbcba687a9946381a440d2570915ec292fb"} 06:32:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:32:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:32:09 INFO - Contents: 06:32:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a27efdc93d2ee89e3bc28a9cc56ddbb83dae07d031068f8049f986e5bb32f8912cda90173975ec21a241cb676d004f2e676075b52251875a9151f71e465c1f1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9886468dbd9554426e924bbacf8e4531695d134d7262c243ebd5a1487012fa40dff956bc7f4ef6b25b81916a4869848a3d459657831833a22fb6a0fbc3e3eef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2924da5a033f2d83e5691d9b4a5592ac65fb28d8a20b58ce6ed4adbb5b294341293fca69baf23b1c92dd93f88a57dfd5d82bd0aa113567edb9292bd7b32dcda6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a4dec548a70d6b9ed5ee3422512cf9ca001e1270df5b0bf92e99f35c42e7c3b72720bce04bd3022c717aab00fe3acbcba687a9946381a440d2570915ec292fb"} 06:32:09 INFO - Running post-run listener: copy_logs_to_upload_dir 06:32:09 INFO - Copying logs to upload dir... 06:32:09 INFO - mkdir: /builds/slave/test/build/upload/logs 06:32:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.191517 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-02-20 06:32:09.229057) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 06:32:09.232127) ========= 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=00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a27efdc93d2ee89e3bc28a9cc56ddbb83dae07d031068f8049f986e5bb32f8912cda90173975ec21a241cb676d004f2e676075b52251875a9151f71e465c1f1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9886468dbd9554426e924bbacf8e4531695d134d7262c243ebd5a1487012fa40dff956bc7f4ef6b25b81916a4869848a3d459657831833a22fb6a0fbc3e3eef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2924da5a033f2d83e5691d9b4a5592ac65fb28d8a20b58ce6ed4adbb5b294341293fca69baf23b1c92dd93f88a57dfd5d82bd0aa113567edb9292bd7b32dcda6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a4dec548a70d6b9ed5ee3422512cf9ca001e1270df5b0bf92e99f35c42e7c3b72720bce04bd3022c717aab00fe3acbcba687a9946381a440d2570915ec292fb"} build_url:https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/BP61zoddRFCVRyWv_Fd5kQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a27efdc93d2ee89e3bc28a9cc56ddbb83dae07d031068f8049f986e5bb32f8912cda90173975ec21a241cb676d004f2e676075b52251875a9151f71e465c1f1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9886468dbd9554426e924bbacf8e4531695d134d7262c243ebd5a1487012fa40dff956bc7f4ef6b25b81916a4869848a3d459657831833a22fb6a0fbc3e3eef4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2924da5a033f2d83e5691d9b4a5592ac65fb28d8a20b58ce6ed4adbb5b294341293fca69baf23b1c92dd93f88a57dfd5d82bd0aa113567edb9292bd7b32dcda6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a4dec548a70d6b9ed5ee3422512cf9ca001e1270df5b0bf92e99f35c42e7c3b72720bce04bd3022c717aab00fe3acbcba687a9946381a440d2570915ec292fb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 06:32:09.256114) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:32:09.256403) ========= 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=00204705a9daa57ed339a427000001b9-1487599875.303735-1280111120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:32:09.343857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 06:32:09.344206) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 06:32:09.344505) ========= ========= Total master_lag: 0.17 =========