builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1487336687.33 results: success (0) revision: 975ce5d8f7d818cc3cd32b134e4010577d3208cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.331176) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.332237) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.332963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.350899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.351581) ========= 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-1487336686.926345-239098679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 05:04:47-- 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% 111M=0s 2017-02-17 05:04:47 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.482273) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.482995) ========= 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-1487336686.926345-239098679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014734 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.534116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:47.534484) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 975ce5d8f7d818cc3cd32b134e4010577d3208cd --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 975ce5d8f7d818cc3cd32b134e4010577d3208cd --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-1487336686.926345-239098679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 05:04:47,791 truncating revision to first 12 chars 2017-02-17 05:04:47,791 Setting DEBUG logging. 2017-02-17 05:04:47,791 attempt 1/10 2017-02-17 05:04:47,791 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/975ce5d8f7d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 05:04:48,094 unpacking tar archive at: mozilla-central-975ce5d8f7d8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662037 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:48.209884) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:48.210258) ========= script_repo_revision: 975ce5d8f7d818cc3cd32b134e4010577d3208cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:48.210705) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:48.211017) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 05:04:48.220826) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-17 05:04:48.221271) ========= /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-1487336686.926345-239098679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:04:48 INFO - MultiFileLogger online at 20170217 05:04:48 in /builds/slave/test 05:04:48 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 05:04:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:48 INFO - {'append_to_log': False, 05:04:48 INFO - 'base_work_dir': '/builds/slave/test', 05:04:48 INFO - 'blob_upload_branch': 'Firefox', 05:04:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:48 INFO - 'branch': 'Firefox', 05:04:48 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:48 INFO - 'config_files': ('talos/linux_config.py',), 05:04:48 INFO - 'default_actions': ('clobber', 05:04:48 INFO - 'read-buildbot-config', 05:04:48 INFO - 'download-and-extract', 05:04:48 INFO - 'populate-webroot', 05:04:48 INFO - 'create-virtualenv', 05:04:48 INFO - 'install', 05:04:48 INFO - 'run-tests'), 05:04:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:48 INFO - 'download_minidump_stackwalk': True, 05:04:48 INFO - 'download_symbols': 'ondemand', 05:04:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:48 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:48 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:48 INFO - 'installer_path': 'installer.exe', 05:04:48 INFO - 'log_level': 'info', 05:04:48 INFO - 'log_name': 'talos', 05:04:48 INFO - 'log_to_console': True, 05:04:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:04:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:04:48 INFO - 'opt_config_files': (), 05:04:48 INFO - 'pip_index': False, 05:04:48 INFO - 'sps_profile': False, 05:04:48 INFO - 'sps_profile_interval': 0, 05:04:48 INFO - 'suite': 'dromaeojs', 05:04:48 INFO - 'system_bits': '32', 05:04:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:48 INFO - 'title': 'talos-linux64-ix-016', 05:04:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:48 INFO - 'use_talos_json': True, 05:04:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:48 INFO - 'work_dir': 'build'} 05:04:48 INFO - [mozharness: 2017-02-17 13:04:48.576572Z] Running clobber step. 05:04:48 INFO - Running pre-action listener: _resource_record_pre_action 05:04:48 INFO - Running main action method: clobber 05:04:48 INFO - rmtree: /builds/slave/test/build 05:04:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:49 INFO - Running post-action listener: _resource_record_post_action 05:04:49 INFO - [mozharness: 2017-02-17 13:04:49.495360Z] Finished clobber step (success) 05:04:49 INFO - [mozharness: 2017-02-17 13:04:49.495460Z] Running read-buildbot-config step. 05:04:49 INFO - Running pre-action listener: _resource_record_pre_action 05:04:49 INFO - Running main action method: read_buildbot_config 05:04:49 INFO - Using buildbot properties: 05:04:49 INFO - { 05:04:49 INFO - "project": "", 05:04:49 INFO - "product": "firefox", 05:04:49 INFO - "who": "cbook@mozilla.com", 05:04:49 INFO - "installer_path": "public/build/target.tar.bz2", 05:04:49 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:04:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:04:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:04:49 INFO - "stage_platform": "linux64-pgo", 05:04:49 INFO - "basedir": "/builds/slave/test", 05:04:49 INFO - "buildnumber": 38, 05:04:49 INFO - "platform": "ubuntu64_hw", 05:04:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:04:49 INFO - "slavebuilddir": "test", 05:04:49 INFO - "taskId": "L4yqJfFbTgaWQ1lx1vkyfw", 05:04:49 INFO - "branch": "mozilla-central", 05:04:49 INFO - "script_repo_revision": "production", 05:04:49 INFO - "revision": "975ce5d8f7d818cc3cd32b134e4010577d3208cd", 05:04:49 INFO - "slavename": "talos-linux64-ix-016", 05:04:49 INFO - "repo_path": "mozilla-central" 05:04:49 INFO - } 05:04:49 INFO - Finding installer, test and symbols from parent task. 05:04:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4yqJfFbTgaWQ1lx1vkyfw'}, attempt #1 05:04:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4yqJfFbTgaWQ1lx1vkyfw'}, attempt #1 05:04:50 INFO - Task dependencies: bkheuIwGQzWQeKLtfoJKuA 05:04:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA'}, attempt #1 05:04:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2 05:04:51 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/test_packages.json 05:04:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.crashreporter-symbols.zip 05:04:51 INFO - Running post-action listener: _resource_record_post_action 05:04:51 INFO - [mozharness: 2017-02-17 13:04:51.023847Z] Finished read-buildbot-config step (success) 05:04:51 INFO - [mozharness: 2017-02-17 13:04:51.024069Z] Running download-and-extract step. 05:04:51 INFO - Running pre-action listener: _resource_record_pre_action 05:04:51 INFO - Running main action method: download_and_extract 05:04:51 INFO - mkdir: /builds/slave/test/build/tests 05:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:51 INFO - trying https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.test_packages.json 05:04:51 INFO - Downloading https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:04:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:04:51 INFO - Downloaded 896 bytes. 05:04:51 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:04:51 INFO - Using the following test package requirements: 05:04:51 INFO - {u'common': [u'target.common.tests.zip'], 05:04:51 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:04:51 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:04:51 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:04:51 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:04:51 INFO - u'mozbase': [u'target.common.tests.zip'], 05:04:51 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:04:51 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:04:51 INFO - u'web-platform': [u'target.common.tests.zip', 05:04:51 INFO - u'target.web-platform.tests.zip'], 05:04:51 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:04:51 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:04:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.common.tests.zip 05:04:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:04:51 INFO - Fetch https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.common.tests.zip into memory 05:04:52 INFO - Expected file size: 59050200 05:04:52 INFO - Obtained file size: 59050200 05:05:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:05:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.common.tests.zip 05:05:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:05:02 INFO - Fetch https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.common.tests.zip into memory 05:05:03 INFO - Expected file size: 59050200 05:05:03 INFO - Obtained file size: 59050200 05:05:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.talos.tests.zip 05:05:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:05:14 INFO - Fetch https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.talos.tests.zip into memory 05:05:15 INFO - Expected file size: 14403647 05:05:15 INFO - Obtained file size: 14403647 05:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:15 INFO - trying https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2 05:05:15 INFO - Downloading https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:05:16 INFO - Downloaded 66776632 bytes. 05:05:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2 05:05:16 INFO - mkdir: /builds/slave/test/properties 05:05:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:16 INFO - Contents: 05:05:16 INFO - build_url:https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2 05:05:16 INFO - Running post-action listener: _resource_record_post_action 05:05:16 INFO - Running post-action listener: set_extra_try_arguments 05:05:16 INFO - [mozharness: 2017-02-17 13:05:16.731501Z] Finished download-and-extract step (success) 05:05:16 INFO - [mozharness: 2017-02-17 13:05:16.731598Z] Running populate-webroot step. 05:05:16 INFO - Running pre-action listener: _resource_record_pre_action 05:05:16 INFO - Running main action method: populate_webroot 05:05:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:16 INFO - '--tppagecycles', 05:05:16 INFO - '7'], 05:05:16 INFO - 'tests': ['tsvgm']}}, 05:05:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['damp', 'tps']}, 05:05:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'tests': ['damp', 'tps']}, 05:05:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['dromaeo_dom']}, 05:05:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['basic_compositor_video']}, 05:05:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['a11yr', 05:05:16 INFO - 'ts_paint', 05:05:16 INFO - 'tpaint', 05:05:16 INFO - 'sessionrestore', 05:05:16 INFO - 'sessionrestore_no_auto_restore', 05:05:16 INFO - 'tabpaint']}, 05:05:16 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:16 INFO - 'ts_paint', 05:05:16 INFO - 'tpaint', 05:05:16 INFO - 'sessionrestore', 05:05:16 INFO - 'sessionrestore_no_auto_restore', 05:05:16 INFO - 'tabpaint']}, 05:05:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:16 INFO - 'ts_paint', 05:05:16 INFO - 'tpaint', 05:05:16 INFO - 'sessionrestore', 05:05:16 INFO - 'sessionrestore_no_auto_restore', 05:05:16 INFO - 'tabpaint']}, 05:05:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:16 INFO - 'ts_paint', 05:05:16 INFO - 'tpaint', 05:05:16 INFO - 'sessionrestore', 05:05:16 INFO - 'sessionrestore_no_auto_restore', 05:05:16 INFO - 'tabpaint']}, 05:05:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['a11yr', 05:05:16 INFO - 'ts_paint', 05:05:16 INFO - 'tpaint', 05:05:16 INFO - 'sessionrestore', 05:05:16 INFO - 'sessionrestore_no_auto_restore', 05:05:16 INFO - 'tabpaint']}, 05:05:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['a11yr', 05:05:16 INFO - 'ts_paint', 05:05:16 INFO - 'tpaint', 05:05:16 INFO - 'sessionrestore', 05:05:16 INFO - 'sessionrestore_no_auto_restore', 05:05:16 INFO - 'tabpaint']}, 05:05:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['tsvgx', 05:05:16 INFO - 'tsvgr_opacity', 05:05:16 INFO - 'tart', 05:05:16 INFO - 'tscrollx', 05:05:16 INFO - 'cart', 05:05:16 INFO - 'tsvg_static']}, 05:05:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:16 INFO - 'tsvgr_opacity', 05:05:16 INFO - 'tart', 05:05:16 INFO - 'tscrollx', 05:05:16 INFO - 'cart', 05:05:16 INFO - 'tsvg_static']}, 05:05:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'talos_options': ['--disable-e10s'], 05:05:16 INFO - 'tests': ['tp5o']}, 05:05:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:05:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'talos_options': ['--disable-e10s', 05:05:16 INFO - '--xperf_path', 05:05:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:16 INFO - 'tests': ['tp5n']}, 05:05:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:05:16 INFO - 'talos_options': ['--xperf_path', 05:05:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:16 INFO - 'tests': ['tp5n']}}, 05:05:16 INFO - 'talos.zip': {'path': '', 05:05:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:16 INFO - Running post-action listener: _resource_record_post_action 05:05:16 INFO - [mozharness: 2017-02-17 13:05:16.744675Z] Finished populate-webroot step (success) 05:05:16 INFO - [mozharness: 2017-02-17 13:05:16.744768Z] Running create-virtualenv step. 05:05:16 INFO - Running pre-action listener: _resource_record_pre_action 05:05:16 INFO - Running main action method: create_virtualenv 05:05:16 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:05:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:16 INFO - Using real prefix '/usr' 05:05:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:19 INFO - Installing pip.................done. 05:05:19 INFO - Return code: 0 05:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:05:19 INFO - Reading from file tmpfile_stdout 05:05:19 INFO - Output received: 05:05:19 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) 05:05:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:19 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:19 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 05:05:19 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 05:05:19 INFO - Using env: {'DISPLAY': ':0', 05:05:19 INFO - 'HOME': '/home/cltbld', 05:05:19 INFO - 'LANG': 'en_US.UTF-8', 05:05:19 INFO - 'LANGUAGE': 'en_US:en', 05:05:19 INFO - 'LOGNAME': 'cltbld', 05:05:19 INFO - 'MAIL': '/var/mail/cltbld', 05:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:19 INFO - 'MOZ_NO_REMOTE': '1', 05:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:19 INFO - 'NO_EM_RESTART': '1', 05:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:19 INFO - 'PWD': '/builds/slave/test', 05:05:19 INFO - 'SHELL': '/bin/bash', 05:05:19 INFO - 'SHLVL': '1', 05:05:19 INFO - 'TERM': 'linux', 05:05:19 INFO - 'TMOUT': '86400', 05:05:19 INFO - 'USER': 'cltbld', 05:05:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:19 INFO - '_': '/tools/buildbot/bin/python'} 05:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:05:19 INFO - Cleaning up... 05:05:19 INFO - Return code: 0 05:05:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:19 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:19 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 05:05:19 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 05:05:19 INFO - Using env: {'DISPLAY': ':0', 05:05:19 INFO - 'HOME': '/home/cltbld', 05:05:19 INFO - 'LANG': 'en_US.UTF-8', 05:05:19 INFO - 'LANGUAGE': 'en_US:en', 05:05:19 INFO - 'LOGNAME': 'cltbld', 05:05:19 INFO - 'MAIL': '/var/mail/cltbld', 05:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:19 INFO - 'MOZ_NO_REMOTE': '1', 05:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:19 INFO - 'NO_EM_RESTART': '1', 05:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:19 INFO - 'PWD': '/builds/slave/test', 05:05:19 INFO - 'SHELL': '/bin/bash', 05:05:19 INFO - 'SHLVL': '1', 05:05:19 INFO - 'TERM': 'linux', 05:05:19 INFO - 'TMOUT': '86400', 05:05:19 INFO - 'USER': 'cltbld', 05:05:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:19 INFO - '_': '/tools/buildbot/bin/python'} 05:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:19 INFO - Downloading/unpacking psutil>=3.1.1 05:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:22 INFO - Installing collected packages: psutil 05:05:22 INFO - Running setup.py install for psutil 05:05:22 INFO - building 'psutil._psutil_linux' extension 05:05:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:05:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:05:22 INFO - building 'psutil._psutil_posix' extension 05:05:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:05:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:05:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:22 INFO - Successfully installed psutil 05:05:22 INFO - Cleaning up... 05:05:22 INFO - Return code: 0 05:05:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:22 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:22 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 05:05:22 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 05:05:22 INFO - Using env: {'DISPLAY': ':0', 05:05:22 INFO - 'HOME': '/home/cltbld', 05:05:22 INFO - 'LANG': 'en_US.UTF-8', 05:05:22 INFO - 'LANGUAGE': 'en_US:en', 05:05:22 INFO - 'LOGNAME': 'cltbld', 05:05:22 INFO - 'MAIL': '/var/mail/cltbld', 05:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:22 INFO - 'MOZ_NO_REMOTE': '1', 05:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:22 INFO - 'NO_EM_RESTART': '1', 05:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:22 INFO - 'PWD': '/builds/slave/test', 05:05:22 INFO - 'SHELL': '/bin/bash', 05:05:22 INFO - 'SHLVL': '1', 05:05:22 INFO - 'TERM': 'linux', 05:05:22 INFO - 'TMOUT': '86400', 05:05:22 INFO - 'USER': 'cltbld', 05:05:22 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:22 INFO - '_': '/tools/buildbot/bin/python'} 05:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:24 INFO - Installing collected packages: mozsystemmonitor 05:05:24 INFO - Running setup.py install for mozsystemmonitor 05:05:25 INFO - Successfully installed mozsystemmonitor 05:05:25 INFO - Cleaning up... 05:05:25 INFO - Return code: 0 05:05:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:25 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')]} 05:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:25 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')]} 05:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:25 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:25 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 05:05:25 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 05:05:25 INFO - Using env: {'DISPLAY': ':0', 05:05:25 INFO - 'HOME': '/home/cltbld', 05:05:25 INFO - 'LANG': 'en_US.UTF-8', 05:05:25 INFO - 'LANGUAGE': 'en_US:en', 05:05:25 INFO - 'LOGNAME': 'cltbld', 05:05:25 INFO - 'MAIL': '/var/mail/cltbld', 05:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:25 INFO - 'MOZ_NO_REMOTE': '1', 05:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:25 INFO - 'NO_EM_RESTART': '1', 05:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:25 INFO - 'PWD': '/builds/slave/test', 05:05:25 INFO - 'SHELL': '/bin/bash', 05:05:25 INFO - 'SHLVL': '1', 05:05:25 INFO - 'TERM': 'linux', 05:05:25 INFO - 'TMOUT': '86400', 05:05:25 INFO - 'USER': 'cltbld', 05:05:25 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:25 INFO - '_': '/tools/buildbot/bin/python'} 05:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:25 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:27 INFO - warning: no files found matching '*.txt' 05:05:27 INFO - no previously-included directories found matching 'build' 05:05:27 INFO - no previously-included directories found matching 'dist' 05:05:27 INFO - no previously-included directories found matching '.git*' 05:05:27 INFO - Installing collected packages: jsonschema, functools32 05:05:27 INFO - Running setup.py install for functools32 05:05:27 INFO - warning: no files found matching '*.txt' 05:05:27 INFO - no previously-included directories found matching 'build' 05:05:27 INFO - no previously-included directories found matching 'dist' 05:05:27 INFO - no previously-included directories found matching '.git*' 05:05:27 INFO - Successfully installed jsonschema functools32 05:05:27 INFO - Cleaning up... 05:05:27 INFO - Return code: 0 05:05:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:27 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:27 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 05:05:27 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 05:05:27 INFO - Using env: {'DISPLAY': ':0', 05:05:27 INFO - 'HOME': '/home/cltbld', 05:05:27 INFO - 'LANG': 'en_US.UTF-8', 05:05:27 INFO - 'LANGUAGE': 'en_US:en', 05:05:27 INFO - 'LOGNAME': 'cltbld', 05:05:27 INFO - 'MAIL': '/var/mail/cltbld', 05:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:27 INFO - 'MOZ_NO_REMOTE': '1', 05:05:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:27 INFO - 'NO_EM_RESTART': '1', 05:05:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:27 INFO - 'PWD': '/builds/slave/test', 05:05:27 INFO - 'SHELL': '/bin/bash', 05:05:27 INFO - 'SHLVL': '1', 05:05:27 INFO - 'TERM': 'linux', 05:05:27 INFO - 'TMOUT': '86400', 05:05:27 INFO - 'USER': 'cltbld', 05:05:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:27 INFO - '_': '/tools/buildbot/bin/python'} 05:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:27 INFO - Cleaning up... 05:05:28 INFO - Return code: 0 05:05:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:28 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:28 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 05:05:28 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 05:05:28 INFO - Using env: {'DISPLAY': ':0', 05:05:28 INFO - 'HOME': '/home/cltbld', 05:05:28 INFO - 'LANG': 'en_US.UTF-8', 05:05:28 INFO - 'LANGUAGE': 'en_US:en', 05:05:28 INFO - 'LOGNAME': 'cltbld', 05:05:28 INFO - 'MAIL': '/var/mail/cltbld', 05:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:28 INFO - 'MOZ_NO_REMOTE': '1', 05:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:28 INFO - 'NO_EM_RESTART': '1', 05:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:28 INFO - 'PWD': '/builds/slave/test', 05:05:28 INFO - 'SHELL': '/bin/bash', 05:05:28 INFO - 'SHLVL': '1', 05:05:28 INFO - 'TERM': 'linux', 05:05:28 INFO - 'TMOUT': '86400', 05:05:28 INFO - 'USER': 'cltbld', 05:05:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:28 INFO - '_': '/tools/buildbot/bin/python'} 05:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:30 INFO - Downloading docopt-0.6.1.tar.gz 05:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:31 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:31 INFO - Running setup.py install for blobuploader 05:05:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:31 INFO - Running setup.py install for requests 05:05:31 INFO - Running setup.py install for docopt 05:05:31 INFO - Successfully installed blobuploader requests docopt 05:05:31 INFO - Cleaning up... 05:05:31 INFO - Return code: 0 05:05:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:31 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')]} 05:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:31 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')]} 05:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:31 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:31 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 05:05:31 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 05:05:31 INFO - Using env: {'DISPLAY': ':0', 05:05:31 INFO - 'HOME': '/home/cltbld', 05:05:31 INFO - 'LANG': 'en_US.UTF-8', 05:05:31 INFO - 'LANGUAGE': 'en_US:en', 05:05:31 INFO - 'LOGNAME': 'cltbld', 05:05:31 INFO - 'MAIL': '/var/mail/cltbld', 05:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:31 INFO - 'MOZ_NO_REMOTE': '1', 05:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:31 INFO - 'NO_EM_RESTART': '1', 05:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:31 INFO - 'PWD': '/builds/slave/test', 05:05:31 INFO - 'SHELL': '/bin/bash', 05:05:31 INFO - 'SHLVL': '1', 05:05:31 INFO - 'TERM': 'linux', 05:05:31 INFO - 'TMOUT': '86400', 05:05:31 INFO - 'USER': 'cltbld', 05:05:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:31 INFO - '_': '/tools/buildbot/bin/python'} 05:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:31 INFO - Running setup.py (path:/tmp/pip-BUVNyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:31 INFO - Running setup.py (path:/tmp/pip-Hf5rQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:32 INFO - Running setup.py (path:/tmp/pip-5SuTlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:32 INFO - Running setup.py (path:/tmp/pip-BBBj8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:32 INFO - Running setup.py (path:/tmp/pip-VLrdEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:32 INFO - Running setup.py (path:/tmp/pip-EimEVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:32 INFO - Running setup.py (path:/tmp/pip-CXNc83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:32 INFO - Running setup.py (path:/tmp/pip-bpk68y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:32 INFO - Running setup.py (path:/tmp/pip-LIVdCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:32 INFO - Running setup.py (path:/tmp/pip-4s8h5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:32 INFO - Running setup.py (path:/tmp/pip-zZ9Nn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:32 INFO - Running setup.py (path:/tmp/pip-hI_bBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:32 INFO - Running setup.py (path:/tmp/pip-X56amx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:32 INFO - Running setup.py (path:/tmp/pip-MvBXNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:32 INFO - Running setup.py (path:/tmp/pip-6hhw84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:32 INFO - Running setup.py (path:/tmp/pip-BmoDOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:33 INFO - Running setup.py (path:/tmp/pip-hadIMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:33 INFO - Running setup.py install for manifestparser 05:05:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Running setup.py install for mozcrash 05:05:33 INFO - Running setup.py install for mozdebug 05:05:33 INFO - Running setup.py install for mozdevice 05:05:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Running setup.py install for mozfile 05:05:33 INFO - Running setup.py install for mozhttpd 05:05:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Running setup.py install for mozinfo 05:05:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Running setup.py install for mozInstall 05:05:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:33 INFO - Running setup.py install for mozleak 05:05:34 INFO - Running setup.py install for mozlog 05:05:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Running setup.py install for moznetwork 05:05:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Running setup.py install for mozprocess 05:05:34 INFO - Running setup.py install for mozprofile 05:05:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Running setup.py install for mozrunner 05:05:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Running setup.py install for mozscreenshot 05:05:34 INFO - Running setup.py install for moztest 05:05:34 INFO - Running setup.py install for mozversion 05:05:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:34 INFO - Cleaning up... 05:05:34 INFO - Return code: 0 05:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:34 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')]} 05:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:34 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')]} 05:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:34 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:34 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 05:05:34 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 05:05:34 INFO - Using env: {'DISPLAY': ':0', 05:05:34 INFO - 'HOME': '/home/cltbld', 05:05:34 INFO - 'LANG': 'en_US.UTF-8', 05:05:34 INFO - 'LANGUAGE': 'en_US:en', 05:05:34 INFO - 'LOGNAME': 'cltbld', 05:05:34 INFO - 'MAIL': '/var/mail/cltbld', 05:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:34 INFO - 'MOZ_NO_REMOTE': '1', 05:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:34 INFO - 'NO_EM_RESTART': '1', 05:05:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:34 INFO - 'PWD': '/builds/slave/test', 05:05:34 INFO - 'SHELL': '/bin/bash', 05:05:34 INFO - 'SHLVL': '1', 05:05:34 INFO - 'TERM': 'linux', 05:05:34 INFO - 'TMOUT': '86400', 05:05:34 INFO - 'USER': 'cltbld', 05:05:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:34 INFO - '_': '/tools/buildbot/bin/python'} 05:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:35 INFO - Running setup.py (path:/tmp/pip-NU_87_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:35 INFO - Running setup.py (path:/tmp/pip-GlqYkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:35 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)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:35 INFO - Running setup.py (path:/tmp/pip-Qrc09T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:35 INFO - Running setup.py (path:/tmp/pip-WQGO_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:35 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)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:35 INFO - Running setup.py (path:/tmp/pip-KIMMO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:35 INFO - Running setup.py (path:/tmp/pip-4AYmqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:35 INFO - Running setup.py (path:/tmp/pip-wuv3NJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:35 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)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:35 INFO - Running setup.py (path:/tmp/pip-gZUe05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:35 INFO - Running setup.py (path:/tmp/pip-pC2FXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:35 INFO - Running setup.py (path:/tmp/pip-LG_7sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:35 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)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:35 INFO - Running setup.py (path:/tmp/pip-Arq5ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:35 INFO - Running setup.py (path:/tmp/pip-VFseno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:36 INFO - Running setup.py (path:/tmp/pip-qDSrs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:36 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)) 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:36 INFO - Running setup.py (path:/tmp/pip-jw1Q16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:36 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)) 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:36 INFO - Running setup.py (path:/tmp/pip-UyLfuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:36 INFO - Running setup.py (path:/tmp/pip-fWoQYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:36 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)) 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:36 INFO - Running setup.py (path:/tmp/pip-gLhn7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:05:36 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)) 05:05:36 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)) 05:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:36 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)) 05:05:36 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)) 05:05:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:38 INFO - Downloading blessings-1.6.tar.gz 05:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:38 INFO - Installing collected packages: blessings 05:05:38 INFO - Running setup.py install for blessings 05:05:38 INFO - Successfully installed blessings 05:05:38 INFO - Cleaning up... 05:05:38 INFO - Return code: 0 05:05:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:38 INFO - Reading from file tmpfile_stdout 05:05:38 INFO - Current package versions: 05:05:38 INFO - argparse == 1.2.1 05:05:38 INFO - blessings == 1.6 05:05:38 INFO - blobuploader == 1.2.4 05:05:38 INFO - docopt == 0.6.1 05:05:38 INFO - functools32 == 3.2.3-2 05:05:38 INFO - jsonschema == 2.5.1 05:05:38 INFO - manifestparser == 1.1 05:05:38 INFO - mozInstall == 1.12 05:05:38 INFO - mozcrash == 1.0 05:05:38 INFO - mozdebug == 0.1 05:05:38 INFO - mozdevice == 0.49 05:05:38 INFO - mozfile == 1.2 05:05:38 INFO - mozhttpd == 0.7 05:05:38 INFO - mozinfo == 0.9 05:05:38 INFO - mozleak == 0.1 05:05:38 INFO - mozlog == 3.4 05:05:38 INFO - moznetwork == 0.27 05:05:38 INFO - mozprocess == 0.24 05:05:38 INFO - mozprofile == 0.28 05:05:38 INFO - mozrunner == 6.13 05:05:38 INFO - mozscreenshot == 0.1 05:05:38 INFO - mozsystemmonitor == 0.3 05:05:38 INFO - moztest == 0.8 05:05:38 INFO - mozversion == 1.4 05:05:38 INFO - psutil == 3.1.1 05:05:38 INFO - requests == 1.2.3 05:05:38 INFO - wsgiref == 0.1.2 05:05:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:38 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:38 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 05:05:38 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 05:05:38 INFO - Using env: {'DISPLAY': ':0', 05:05:38 INFO - 'HOME': '/home/cltbld', 05:05:38 INFO - 'LANG': 'en_US.UTF-8', 05:05:38 INFO - 'LANGUAGE': 'en_US:en', 05:05:38 INFO - 'LOGNAME': 'cltbld', 05:05:38 INFO - 'MAIL': '/var/mail/cltbld', 05:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:38 INFO - 'MOZ_NO_REMOTE': '1', 05:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:38 INFO - 'NO_EM_RESTART': '1', 05:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:38 INFO - 'PWD': '/builds/slave/test', 05:05:38 INFO - 'SHELL': '/bin/bash', 05:05:38 INFO - 'SHLVL': '1', 05:05:38 INFO - 'TERM': 'linux', 05:05:38 INFO - 'TMOUT': '86400', 05:05:38 INFO - 'USER': 'cltbld', 05:05:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:38 INFO - '_': '/tools/buildbot/bin/python'} 05:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 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)) 05:05:39 INFO - Cleaning up... 05:05:39 INFO - Return code: 0 05:05:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:39 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')]} 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:39 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')]} 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:39 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 0x20031f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, '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-1487336686.926345-239098679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:05:39 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 05:05:39 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 05:05:39 INFO - Using env: {'DISPLAY': ':0', 05:05:39 INFO - 'HOME': '/home/cltbld', 05:05:39 INFO - 'LANG': 'en_US.UTF-8', 05:05:39 INFO - 'LANGUAGE': 'en_US:en', 05:05:39 INFO - 'LOGNAME': 'cltbld', 05:05:39 INFO - 'MAIL': '/var/mail/cltbld', 05:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:39 INFO - 'MOZ_NO_REMOTE': '1', 05:05:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:39 INFO - 'NO_EM_RESTART': '1', 05:05:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:39 INFO - 'PWD': '/builds/slave/test', 05:05:39 INFO - 'SHELL': '/bin/bash', 05:05:39 INFO - 'SHLVL': '1', 05:05:39 INFO - 'TERM': 'linux', 05:05:39 INFO - 'TMOUT': '86400', 05:05:39 INFO - 'USER': 'cltbld', 05:05:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:39 INFO - '_': '/tools/buildbot/bin/python'} 05:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:05:39 INFO - Cleaning up... 05:05:39 INFO - Return code: 0 05:05:39 INFO - Running post-action listener: _resource_record_post_action 05:05:39 INFO - Running post-action listener: _start_resource_monitoring 05:05:39 INFO - Starting resource monitoring. 05:05:39 INFO - [mozharness: 2017-02-17 13:05:39.446095Z] Finished create-virtualenv step (success) 05:05:39 INFO - [mozharness: 2017-02-17 13:05:39.446561Z] Running install step. 05:05:39 INFO - Running pre-action listener: _resource_record_pre_action 05:05:39 INFO - Running main action method: install 05:05:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:39 INFO - Reading from file tmpfile_stdout 05:05:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:39 INFO - Reading from file tmpfile_stdout 05:05:39 INFO - Output received: 05:05:39 INFO - Usage: mozinstall [options] installer 05:05:39 INFO - Options: 05:05:39 INFO - -h, --help show this help message and exit 05:05:39 INFO - -d DEST, --destination=DEST 05:05:39 INFO - Directory to install application into. [default: 05:05:39 INFO - "/builds/slave/test"] 05:05:39 INFO - --app=APP Application being installed. [default: firefox] 05:05:39 INFO - mkdir: /builds/slave/test/build/application 05:05:39 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'] 05:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:05:56 INFO - Reading from file tmpfile_stdout 05:05:56 INFO - Output received: 05:05:56 INFO - /builds/slave/test/build/application/firefox/firefox 05:05:56 INFO - Running post-action listener: _resource_record_post_action 05:05:56 INFO - [mozharness: 2017-02-17 13:05:56.390217Z] Finished install step (success) 05:05:56 INFO - [mozharness: 2017-02-17 13:05:56.390493Z] Running run-tests step. 05:05:56 INFO - Running pre-action listener: _resource_record_pre_action 05:05:56 INFO - Running main action method: run_tests 05:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:56 INFO - Python 2.7.3 05:05:56 INFO - Return code: 0 05:05:56 INFO - grabbing minidump binary from tooltool 05:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232d540>, '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 0x22a1f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a2400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:05:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:05:56 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 05:05:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:56 INFO - Return code: 0 05:05:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:05:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:56 INFO - ENV: RUST_BACKTRACE is now 1 05:05:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:05:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:56 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/bkheuIwGQzWQeKLtfoJKuA/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 05:05:56 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/bkheuIwGQzWQeKLtfoJKuA/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 05:05:56 INFO - Using env: {'DISPLAY': ':0', 05:05:56 INFO - 'HOME': '/home/cltbld', 05:05:56 INFO - 'LANG': 'en_US.UTF-8', 05:05:56 INFO - 'LANGUAGE': 'en_US:en', 05:05:56 INFO - 'LOGNAME': 'cltbld', 05:05:56 INFO - 'MAIL': '/var/mail/cltbld', 05:05:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:56 INFO - 'MOZ_NO_REMOTE': '1', 05:05:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:56 INFO - 'NO_EM_RESTART': '1', 05:05:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:56 INFO - 'PWD': '/builds/slave/test', 05:05:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:56 INFO - 'RUST_BACKTRACE': '1', 05:05:56 INFO - 'SHELL': '/bin/bash', 05:05:56 INFO - 'SHLVL': '1', 05:05:56 INFO - 'TERM': 'linux', 05:05:56 INFO - 'TMOUT': '86400', 05:05:56 INFO - 'USER': 'cltbld', 05:05:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1487336686.926345-239098679', 05:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:56 INFO - '_': '/tools/buildbot/bin/python'} 05:05:56 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/bkheuIwGQzWQeKLtfoJKuA/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 05:05:56 INFO - mozversion application_buildid: 20170217114148 05:05:56 INFO - mozversion application_changeset: 975ce5d8f7d818cc3cd32b134e4010577d3208cd 05:05:56 INFO - mozversion application_display_name: Nightly 05:05:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:56 INFO - mozversion application_name: Firefox 05:05:56 INFO - mozversion application_remotingname: firefox 05:05:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:56 INFO - mozversion application_vendor: Mozilla 05:05:56 INFO - mozversion application_version: 54.0a1 05:05:56 INFO - mozversion platform_buildid: 20170217114148 05:05:56 INFO - mozversion platform_changeset: 975ce5d8f7d818cc3cd32b134e4010577d3208cd 05:05:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:56 INFO - mozversion platform_version: 54.0a1 05:05:56 INFO - using testdate: 1487336756 05:05:56 INFO - actual date: 1487336756 05:05:56 INFO - starting webserver on 'localhost:33229' 05:05:56 INFO - SUITE-START | Running 2 tests 05:05:56 INFO - TEST-START | dromaeo_css 05:05:56 INFO - operating with platform_type : linux_ 05:05:56 INFO - Initialising browser for dromaeo_css test... 05:05:56 INFO - TEST-INFO | started process 25071 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMP_WlP/profile http://localhost:33229/getInfo.html) 05:05:59 INFO - PROCESS | 25071 | Iterating over profile /tmp/tmpMP_WlP/profile 05:06:00 INFO - PROCESS | 25071 | __metrics Screen width/height:1600/1200 05:06:00 INFO - PROCESS | 25071 | colorDepth:24 05:06:00 INFO - PROCESS | 25071 | Browser inner width/height: 1024/697 05:06:00 INFO - PROCESS | 25071 | __metrics 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/secmod.db = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/key3.db = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/webappsstore.sqlite = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/prefs.js = Fri Feb 17 2017 05:05:15 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/compatibility.ini = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/mimeTypes.rdf = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/permissions.sqlite = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/gmp = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/cookies.sqlite = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/sessionCheckpoints.json = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/addons.json = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/directoryLinks.json = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/safebrowsing = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/content-prefs.sqlite = Fri Feb 17 2017 05:05:58 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/places.sqlite = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/crashes = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/pluginreg.dat = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | Stat failure 05:06:00 INFO - PROCESS | 25071 | Stat failure 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/places.sqlite-shm = Fri Feb 17 2017 05:05:58 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | Stat failure 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/storage.sqlite = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/places.sqlite-wal = Fri Feb 17 2017 05:06:00 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/frequencyCap.json = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/bookmarkbackups = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/cache2 = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/minidumps = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/thumbnails = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/.parentlock = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/blocklist.xml = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/extensions.ini = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/user.js = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/cert8.db = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/startupCache = Fri Feb 17 2017 05:05:56 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/extensions = Fri Feb 17 2017 05:05:57 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/localstore.rdf = Fri Feb 17 2017 05:05:15 GMT-0800 (PST) 05:06:00 INFO - PROCESS | 25071 | No birth date. Using mtime. 05:06:00 INFO - PROCESS | 25071 | Using date: /tmp/tmpMP_WlP/profile/extensions.json = Fri Feb 17 2017 05:05:59 GMT-0800 (PST) 05:06:01 INFO - PROCESS | 25071 | 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" 05:06:01 INFO - PROCESS | 25071 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:02 INFO - TEST-INFO | 25071: exit 0 05:06:02 INFO - Browser initialized. 05:06:02 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:02 INFO - TEST-INFO | started process 25163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMP_WlP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:14 INFO - PROCESS | 25163 | RSS: Main: 142868480 05:06:14 INFO - PROCESS | 25163 | 05:09:16 INFO - PROCESS | 25163 | Cycle 1(1): loaded http://localhost:33229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33229/tests/dromaeo/cssquery-ext.html) 05:09:18 INFO - PROCESS | 25163 | RSS: Main: 263118848 05:09:18 INFO - PROCESS | 25163 | 05:11:03 INFO - PROCESS | 25163 | ************************* 05:11:03 INFO - PROCESS | 25163 | A coding exception was thrown and uncaught in a Task. 05:11:03 INFO - PROCESS | 25163 | 05:11:03 ERROR - PROCESS | 25163 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:11:03 INFO - PROCESS | 25163 | Full stack: 05:11:03 INFO - PROCESS | 25163 | ************************* 05:12:20 INFO - PROCESS | 25163 | Cycle 1(1): loaded http://localhost:33229/tests/dromaeo/cssquery-ext.html (next: http://localhost:33229/tests/dromaeo/cssquery-jquery.html) 05:12:21 INFO - PROCESS | 25163 | RSS: Main: 243990528 05:12:21 INFO - PROCESS | 25163 | 05:15:23 INFO - PROCESS | 25163 | Cycle 1(1): loaded http://localhost:33229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33229/tests/dromaeo/cssquery-mootools.html) 05:15:24 INFO - PROCESS | 25163 | RSS: Main: 238743552 05:15:24 INFO - PROCESS | 25163 | 05:18:25 INFO - PROCESS | 25163 | Cycle 1(1): loaded http://localhost:33229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33229/tests/dromaeo/cssquery-prototype.html) 05:18:26 INFO - PROCESS | 25163 | RSS: Main: 179257344 05:18:26 INFO - PROCESS | 25163 | 05:21:29 INFO - PROCESS | 25163 | Cycle 1(1): loaded http://localhost:33229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33229/tests/dromaeo/cssquery-yui.html) 05:21:29 INFO - PROCESS | 25163 | RSS: Main: 178655232 05:21:29 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | Cycle 1(1): loaded http://localhost:33229/tests/dromaeo/cssquery-yui.html (next: http://localhost:33229/tests/dromaeo/cssquery-dojo.html) 05:24:32 INFO - PROCESS | 25163 | RSS: Main: 280743936 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | __start_tp_report 05:24:32 INFO - PROCESS | 25163 | _x_x_mozilla_page_load 05:24:32 INFO - PROCESS | 25163 | _x_x_mozilla_page_load_details 05:24:32 INFO - PROCESS | 25163 | |i|pagename|runs| 05:24:32 INFO - PROCESS | 25163 | |0;dojo.html;3148.496240601504;3189.394015137122;3192.2976945072082;3212.710856022959;3223.822689752064;5088.18589025756;5229.267902493654;5259.737013149342;5338.27933229014;5345.465453454657;5235.921461178076;5241.5806735461165;5328;5355.678659280446;5393.217983392416;4579.610733087691;4741.668083234175;4745.715257084577;4780.474147843752;4903.975480122613;4894.412670479547;4936.128610691275;4953.058918805434;4954.7027178369235;4957.107720610289;4642.9071418571775;4660.067986402699;4661.906761864744;4704.764761761932;4817.507307202268;4694.788734506956;4738.928916066262;4740.123077230689;4741;4807.038592281558;4701.529847015306;4715.669903106784;4723.669343145981;4757.00102978375;4778.80884764609;5256.842294731164;5258.0535503609335;5270.183121616155;5282.7622756976025;5292;5517.972410137963;5538.113901775737;5552.833414997556;5641.52047075995;5689.516391106761;5623.465770751772;5671.461211184931;5676.659400435987;5677.148427735831;5678.403767604384;5317.335333083364;5319.946800532022;5323.733813309319;5325.440828712968;5358.678479291255;5875.676837773878;5931.436513531207;5986.132010858489;5990.131430942489;5992.0712289595185;5427;5453.15476101205;5463.562914966793;5713.5714821388565;5751.022326204556;5637.802676906289;5645.774169033275;5645.830625081254;5649.774009039593;5651.321841379061;5605.271314729059;5610.1584762285165;5670.177824215548;5683.658980461186;5694.316681998187;5784.074548072373;5786.508146807569;5789.247397838339;5791.5656325775735;5792.218050563204;4257.723247988871;4281.336392859112;4431.180231657154;4440.2007638625355;4453.888652783641;3982.123932734794;3992.8802135935966;3995.300822356134;4000.8399664013114;4015.9397609035886;5410.648307859977;5419.593530485151;5419.918701219485;5420.972895135499;5456.972715136479;5543.695096769636;5580.135079062752;5582.525485333711;5593.74828132735;5655.066913959151;5295.4439783822145;5308.522232999048;5311.362636483646;5314.548263397577;5361.678299301976;4945.802167913315;5033.1695270280725;5041.798328066836;5042.924356134661;5046;6080.695965201722;6086.60437071589;6088.452039316483;6090.390960903874;6132.7546898124465;5763.884722305615;5816.680082595498;5824.70876456167;5826.863761566454;5831.533477321719;5785.971070144622;5799.217105690678;5849.093390524476;5932.733027013708;5933.169356290036;5603.495685388335;5611.270534830446;5613.494785469327;5614.775408983595;5619.943800562106;5674.404187560247;5674.574406919415;5677.659340439587;5678.574106941914;5679.261695979496;5512.118061110203;5560.7219639018695;5565.471280228372;5652.095664693629;5656.349519805275;5385.461453854583;5428.945710542844;5429.755660995185;5633.971830140823;5644.096944488862;5458.972705136449;5466.480684334982;5467.125259958388;5467.58993075513;5469.890602188014;5501.504864562048;5632.802851900164;5646.407127251579;5651.915221271602;5727.942720572741;5545.528630066489;5546.195801608825;5564.360098588552;5572.693501857437;5573.414791446839 05:24:32 INFO - PROCESS | 25163 | |1;ext.html;544.9428109467633;602.9216201893753;612.8039027511195;613.1875265273512;640.9262934762504;30636.24273815356;31117;43804.12391752167;45099.77450112753;45360.3195952061;43756.3436548452;47212.76393618036;47228;47247.52752472474;47356;9074.410163339395;9270.536473176347;9279.536023198847;9281.118293762082;9284.489353085577;7517.736879209228;7722.884156737653;8703.216710496054;10599.09907657846;10884.891151088506;10884.292520986111;10964.342139471617;10964.451777411137;10998.06516446103;10998.12015038799;10950;10972.890271097307;11029.44852757367;11043;11047;10699.946500267526;10768.946155269174;10776.19178561611;10776.73058173544;10779;43711.344329835076;44104.77947610273;44148;44322.778386108184;44352.556474435165;3758.1356288053903;3802.5627052888995;3821.6433564691306;3846.557645870733;3912.647861692433;31890.840545797353;32106.518402223985;32285;32343.51484727731;32548;3506.3513250048823;3655.670989610948;3695.4456831475227;3820.9808950955344;3829.7127715421457;308803;310637;311097;311698.44150779326;313407;61415.38584614186;61495.38504614986;61578.38421615816;61655.38344616586;61685.364049033175;64659;64923;65019.34980650133;65250.34749652538;65445;15436;15617.14105724196;15634.107690077371;15640.2179891005;15644.139572323631;10299.691009269734;10892.074173695166;10915.454227288605;10924.071453926505;10933.671989840317;15090.773638395432;17872.642547148986;18287.817121828877;18331.81668183328;19228.134733937008;5949.524038076944;6744.83137921546;6914.239433662293;6959.6941452809515;7044.577325360495;889.8042430665244;896.7533928169805;897.8574987543598;902.4044130873592;926.8702381666573;30389.848050759603;30425.23936901595;30899.69100309013;31188.68811311858;31224.21939451532;1077.7467295185625;1079.7570546626946;1081.9350838949688;1088.5482524752297;1124.2299025167786;15731;16252.268647910872;16260.75608865868;16276.26756795947;16600.027988244965;451818;457301;457714;457763;457969;49659.25511117336;50923.23615145776;59228.40771592315;60693;60765.08852367218;1385.757492438819;1395.295375835217;1411.7176564687104;1415.490423447558;1416.2776983738368;30790.53675705983;32839.507407389385;32986.17534561655;33060;33849.3230135391;53566;54252;54284.185737213185;54368.45631543634;54380.11488334249;402901;404961;405629;405707;406113;40638.26277350384;40962.38556421596;41356;41684.374734378405;41713.37429938493;45997.310040348755;46576.50676199231;46953.29570056384;47435.76282118567;47448.05103897972;37771.62228377681;39406.408903867035;39420.80289598648;40550.39174412445;40995.38506922458;35384;36154.276914461036;36589.81705091458;37953.81023094867;40624.59375406208;37457.4381384274;37737.245255095295;38582.4212636805;38752.6124738749;38758.4186237201;29086.854565727037;32182.839085805357;32711.18222044468;33003.339933201685;33013.83493082519;25275.74724252734;25488.74511254938;25534.106306279933;26062;26226.60660090062 05:24:32 INFO - PROCESS | 25163 | |2;jquery.html;387.88169608269504;423.208322137346;428.45800062920404;439.30808975863016;443.2929477483413;15533.689326213484;19084.141213645402;19646.99999999998;21037.58067822201;21167.364979050653;20235.797642023575;20536.38390848273;21029.474263143427;21074.15703371863;21209.36371908845;5907.261592300963;5925.259342582177;6145.8156255312415;6153.292371377282;6158.815235542941;5967.791127310545;6008.549358798085;6014.75940962361;6016.157737916695;6019.277686677605;5799.768009279624;5977.193078934331;5985.16207730918;5997.100434934751;5997.37027612101;5997.940020599782;6033.849153771147;6055.818325450244;6076.4834989025985;6080.787172448965;5419.945800541984;5420.051490989101;5465.180222966547;5469.206964990054;5476.780928762844;21590.24434144813;22194.88902555493;22466.663000055014;24043.39891502709;24251.87874060636;2807.971920280812;2809.634747482814;2823.957640635392;2830.547112461996;2877.079334612925;10052.418453283015;10229.948850255701;10319.43243121628;10578.312409693346;10653.041226289593;1826.3425166939892;1860.4186441524596;1868.2620364955767;1884.4629280654947;1892.6971684530408;81450.59274703648;86041.56979215064;86543;88511.55744221302;88927.99999999936;10167.440790756431;11784.58753943625;11935.582254621047;12037;12182.573609923611;14510.85489145095;15324.693506129815;15420;17322;17343.739843902353;8030.277275045274;8079.798005049921;8258.398604439582;8371.246587807127;8379.329653627696;7421.406287496988;7486.176520582731;7505.812354691177;7637.961810190914;7697.153313135551;5132.589392848563;5242.397124330674;5255.947440525622;5327.706976116351;5332.733363331818;2059.1969132038516;2110.314147901938;2152;2191.386411804697;2224.566209589115;976.7851072763981;977.5991843344337;978.7161723100363;984.3601658921758;991.1723710701501;20000.999950002733;20023.899880500794;20039;20053.197872084955;20110.296139635044;936.8735220745112;938.7324612485409;980.7352014956065;997.6607953295769;1007.4459047523833;10186.439745814052;10767.3539587625;10820.621278255381;10848.566057357775;10862.619808306672;599338;646597;665555;670619;676168;88784.55607722049;93118;101624.49187754162;103848.48075759572;104129;1040.7086015915556;1069.7860427914604;1101.0365929811414;1105.8443926097248;1106.6458733205297;17372.565685857953;17748.645027099126;17928.731069033714;18512.629747405244;18930;41497.58502415058;42058.36912446377;42507.57492425036;43693;44059.205494654016;58875.41124588699;58930.41069589249;59888;60281;61109.69445152746;8210.3021243195;8320.16798320163;8383.329333653171;8503.914960850312;8850.64597416122;10636.680899573024;12500.499980001063;12820.551280705131;13231.66920826987;13493.122947008413;10964.451777411257;11131.109511239243;11226.270292430965;11320.943395282971;11630.53477860876;8490.872636910328;11642.76714465723;11744.647660570197;11791.35147566892;11869.050475962074;8251.174882511701;8729.476231426135;8739.606717697847;8871.112888711077;9038.231750301298;12307.630771076882;12720.883383570714;12930.353482325658;13101.541446049343;13445.932770336085;12507.56223532172;12518.812217816558;12528.185667931555;12692.682682932631;12990.610281691565 05:24:32 INFO - PROCESS | 25163 | |3;mootools.html;1410.8800751936085;1426.3153686230603;1436.0737324425745;1446.6600348918003;1447.5078473319072;8232.259096681297;8689.348298877578;8699.217070463657;8701.608427620764;8702.26030787383;8370.790730231747;8518.40371174018;8593.054763975957;8752.649894004233;8759.51822649754;2231.5313784105315;2285.5543169082034;2288.107638021173;2289.7595752446005;2291.140822191678;1879.7086451600023;2208.536207396449;2259.6271615183473;2260.480089579398;2264.1509433962265;1912.80871912809;2168.2086038595876;2235.7540670526228;2241.6301310283784;2265.728112626479;2115.4394085567337;2124.2671278408925;2125.596136734031;2125.8937053147433;2128.861059333257;1934.071645610115;2121.140937920145;2200.2409168836725;2207.8896055197174;2211.6793065005486;8709.651613935435;8781.077986811357;8791.604377803014;8793.449899529129;8797.516136612483;5033.144365457881;5177.48225177483;5181.844544663666;5188.22176673502;5188.273641690166;3066.540018997146;3259.2014956335847;3259.6251431085493;3280.9015729527914;3284.5237440571223;1292.1536393662166;1297.4810075969688;1351.2162945491689;1374.0519041861085;1382.7549423270564;11676.124290678148;11691.356975366352;11772.64682059531;11824.586139485076;11825.645230643095;4021.6581590564547;4267.338562522814;4301.483821941387;4309.159713855768;4318.848840290575;9367.812643747087;9378.43729376226;9404.200642945427;9404.341696081345;9420.576074076545;5036.445990941067;5048.596112311006;5078.4413714491375;5090.26191202281;5091.210862316347;4593.609543188866;4632.60622847055;4732.219183834698;4740.525947405232;4745.240761478147;3534.5228394166456;3628.63713628635;3646.4712616670436;3662.8351724171944;3678;1070.1866581398037;1082.967510974672;1085.3542142425244;1092.3773449134076;1103.87857335693;847.9067302596709;863.0808189278398;881.352206128495;891.4205766251988;896.1755185229605;5474.863128421742;5499.587530935196;5513.228148059275;5584.86037849057;5724.627899186539;578.2338401617873;580.0979476913355;587.8853623543368;595.4016213705198;606.6602702486622;3452.1369657585606;3567.3935430976794;3646.4712616670436;3739.8691045813266;3887.922241555153;9935.552900119512;10163;10377.792444151075;10438.112760415297;10449.425281609585;10300.124489418333;10347.689569312932;10350.171986241083;10437.478126093665;10465.529051192714;7803.882941755878;7947.245011723877;8045.356371490267;8047.155048719917;8056.194380561897;5452.400235974119;5457.372402173642;5484.917726234189;5516.034693928626;5643.604947653625;9222.861657075015;9244.583993720433;9291.303152263472;9295;9437.622495100119;9551.188149007412;9588;9666.516674166403;9669.178119859891;9693.933667296733;7019.333163349474;7098.041764361951;7100.076989991475;7101.751438699621;7102.041224434635;7924.881126782988;8048.195180481905;8086.231807978233;8144.104148543537;8145.144759800136;7077.858442831218;7104.147502299757;7159.821004474929;7168.569885806868;7359.337659610661;6943.340382663639;6959.61722105289;6988.580685158907;6998.230194678479;7004.964975175091;6226.1905952225925;6241.31345551998;6309.432151106423;6345.841353966003;6415.845147873428;4853.757312134449;4866.075445665454;4926.408830940167;4928.852134435972;5018.297438358706;4890.510948905081;4957.4794646561595;4964.180910149785;4992.2511623256805;5013.047520971004 05:24:32 INFO - PROCESS | 25163 | |4;prototype.html;368.6203210692987;380.2129591745088;383.1780830119394;384.41952651496234;385.54698229580254;5257.185136303879;5268.6575372600755;5273.6572122811995;5289.841304760854;5300.204969254613;4993.650444468889;5156.948430515694;5206.557442617382;5222.120036786754;5232.241325007872;4140.130572579762;4168.749875007494;4170.249355116078;4174.895627609319;4187.937180942288;4173.309809527132;4184.8535301264465;4189.413482112492;4195.85314513992;4207.894802629929;4169.457970463851;4184.225918205127;4187.74873507589;4187.937180942288;4198.677416613772;3852.5376954765466;4035.3341698619693;4045.9797701011603;4055.35114381699;4069.409935559357;3575;3580.122869896892;3587.5694916609914;3598.7300952428673;3606.459031145323;5122.590192784605;5147.4080480744815;5149.845504634867;5193.922091168635;5210.525000624711;4615.053913947666;4712.9057418851435;4734;4751.809927602892;4767.4994125617;5507.642003269774;5526.972365138189;5531.419200983919;5545.225527831096;5549.694766787825;3981.9601803981877;3989.581093985119;4015.072765072752;4015.1768887378016;4022.5776293489057;5574.832755017356;5693.807883964662;5736.913946290809;5750.79872204475;5757.424257574251;4105;4129.566395528486;4133.503979522477;4141.275276826544;4142.08874047709;4183.97908010458;4200.4539409876525;4216.409702641633;4223.5142958559545;4261.147770445923;4619.491855895849;4668.276417155346;4703.529647035269;4760.619150467955;4810.436608102381;5534.169874518854;5545;5557.416471270459;5579.437757427927;5585.078462053716;1400.236870905359;1405.8612523855709;1410.3864818803854;1414.429982719181;1416.8441471438134;905.5653286422554;974.7319487141093;977.9022097790164;978.8091322192104;987.3039507147587;5140.485951404904;5185.818496352609;5187.06632806091;5206.062908676475;5228.686278823283;5563.554915606742;5569.860753481195;5626.662400255997;5638.182463542763;5662.009148399014;4986.3794266863415;5069.417017043013;5135.948640513622;5139;5221.556167725786;2913.490139225627;2923.956140657892;2944.2628848979207;3001.8292865782364;3005.2419334689016;5878.470937615635;5890.764369425261;5895.64626122434;5905.852353691193;5915.639402937349;5397.784088636489;5485.9725701371235;5552;5602.431319230622;5682.886342273131;5761.654300741969;5794.50746686528;5900.498457630978;5943.613665111754;5947.895951793249;5658.122990936246;5678.5457163426;5687.43125687428;5696.715164241688;5698.1737648041635;5447.400785913625;5570.554355651457;5628.60599758013;5642.23829782974;5683.788659407422;5986.730597123227;6007.605702777916;6018.247719035121;6032.1856549365275;6038.396160383926;4128.8348466061025;4128.91742165161;4161.812718427617;4166.312558427825;4166.8749937501925;5411.107167317348;5697.6296540724725;5700.25896633435;5706.800261990977;5718.828435146952;5537.861553461195;5541.224228607917;5545.861353466195;5555.111182210989;5616.185653080199;5250.238715386324;5487.396386397573;5536.307961504812;5543.307086614173;5587.552995760248;5553.305836770403;5556.499915007507;5559.610827242216;5580.9071598150695;5583.330000399978;5602.5517958562405;5609.439056094521;5635.239242702182;5653.265075540318;5669.489745922887;5215.217717342429;5434.402215756343;5482.451754824486;5497.955388476177;5514.641548299348 05:24:32 INFO - PROCESS | 25163 | |5;yui.html;737.9852402951944;763.4922776353728;781.8827175923615;828.9626966786495;836.9079401265859;14260.144391336527;15364.61588460289;15440.305186266616;15448.845511544909;15468.303926323315;13627.386767595483;13635.386407611657;13642.113262637946;13642.386092625831;13648.795268070988;787.4920676163899;797.4776521378492;800.0719165767711;812.4718932693767;816.4448175240834;823.3495538524559;825.5665775467868;828.5939889454185;828.9129641387658;829.5437509369823;850.4004676702909;855.9743207703748;857.1899554920572;857.6097875466679;861.3023451004675;853.624405261686;853.9316854651644;855.2815634866711;858.0604238358987;861.0958493581714;632.0393002635967;632.0866348126958;633.0472638678805;633.0820310549675;633.3128555517269;14152.36314365856;14501.129932203996;15053.322600483005;15068.095914245181;15130;14322.570322890226;14650.340734666966;14654.267286635626;14829.925850370679;14902;11596.246243994114;11928.165028448013;12033.93983030088;12168.209066410744;12528.436220370015;3604.4953706481115;3609.8736544221088;3668.633136686337;3675.558932928039;3715.6841668458214;75762.57826558998;77509.61245193794;78477.60761196158;78628.21371786266;79633.60183199047;11132.721681958015;11328.830067548994;11351.262167959056;11361;11394.487248073856;20224.292149774687;20241;20249.291274805604;20288.391348259574;20307.898460507604;7196.532225405332;7378.188399276075;7543.509671871311;7557.962210188914;7713.7685869424;3852.499175107218;3918.6277303656107;4010.7593544387432;4043.2722110020477;4121.443605113272;3622.7464077514846;3703.9074023149096;3727.1800203955086;3731.6828069613844;3753;595.3867516458055;610.9938900611025;615.123449085061;623.4762799248653;625.343389441085;649.357136434926;650.6714109374735;678.4165617568887;681.1383599746325;695.4922906278344;9344.439333639868;9478.241740660731;9686.353319935628;9813.607455701835;9997.750056248651;662.8773676869795;689.4002218070211;689.8137502874196;696.8258484453712;704.8625518023936;4449.28811390176;4657.510961349009;4740.597049250852;5196.948030519647;5267.394249661338;70601;72024.63987680028;72731.63634181795;72900;75464;50931;57314.7134264326;62091;62653.68673156696;66795;56969.43030569641;57172.71413642905;57226.427735722944;57457;57564.424355756746;9967.551460184164;10229.795404092025;10460.476976151314;10507.789844203226;10607.94696026515;37986.810065949496;39182;39472;39539.42993705515;40935;51564.74217628888;53890;54219.45780542144;54416;55729.44270557242;12987.285699286253;13262.270575118075;13290.601281961557;13560.93219533896;13764.793528097287;13470.463187520569;13738.587842364745;13744.931275343559;14604.26978651042;14789.11265324084;14074.84138288243;14292.499762508261;14518.128912265298;14842.703145937237;14983;13661.482140992097;13730.1426115901;13966.37151328213;14145;14419;13220.272884991418;13422.194668319931;13555.525556605471;13618.727625447238;14172.291385430894;11205.831912521156;11301.773964520828;11531.628372225;11829.70425739329;11961.940190298992;10114.494275286354;10122;10261.384316941007;11123.27698699582;11510.654680359603 05:24:32 INFO - PROCESS | 25163 | __end_tp_report 05:24:32 INFO - PROCESS | 25163 | __start_cc_report 05:24:32 INFO - PROCESS | 25163 | _x_x_mozilla_cycle_collect,1335 05:24:32 INFO - PROCESS | 25163 | __end_cc_report 05:24:32 INFO - PROCESS | 25163 | __startTimestamp1487337872961__endTimestamp 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | ------- Summary: start ------- 05:24:32 INFO - PROCESS | 25163 | Number of tests: 6 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | [#0] dojo.html Cycles:165 Average:5291.49 Median:5466.48 stddev:596.51 (10.9%) stddev-sans-first:574.16 05:24:32 INFO - PROCESS | 25163 | Values: 3148.5 3189.4 3192.3 3212.7 3223.8 5088.2 5229.3 5259.7 5338.3 5345.5 5235.9 5241.6 5328.0 5355.7 5393.2 4579.6 4741.7 4745.7 4780.5 4904.0 4894.4 4936.1 4953.1 4954.7 4957.1 4642.9 4660.1 4661.9 4704.8 4817.5 4694.8 4738.9 4740.1 4741.0 4807.0 4701.5 4715.7 4723.7 4757.0 4778.8 5256.8 5258.1 5270.2 5282.8 5292.0 5518.0 5538.1 5552.8 5641.5 5689.5 5623.5 5671.5 5676.7 5677.1 5678.4 5317.3 5319.9 5323.7 5325.4 5358.7 5875.7 5931.4 5986.1 5990.1 5992.1 5427.0 5453.2 5463.6 5713.6 5751.0 5637.8 5645.8 5645.8 5649.8 5651.3 5605.3 5610.2 5670.2 5683.7 5694.3 5784.1 5786.5 5789.2 5791.6 5792.2 4257.7 4281.3 4431.2 4440.2 4453.9 3982.1 3992.9 3995.3 4000.8 4015.9 5410.6 5419.6 5419.9 5421.0 5457.0 5543.7 5580.1 5582.5 5593.7 5655.1 5295.4 5308.5 5311.4 5314.5 5361.7 4945.8 5033.2 5041.8 5042.9 5046.0 6080.7 6086.6 6088.5 6090.4 6132.8 5763.9 5816.7 5824.7 5826.9 5831.5 5786.0 5799.2 5849.1 5932.7 5933.2 5603.5 5611.3 5613.5 5614.8 5619.9 5674.4 5674.6 5677.7 5678.6 5679.3 5512.1 5560.7 5565.5 5652.1 5656.3 5385.5 5428.9 5429.8 5634.0 5644.1 5459.0 5466.5 5467.1 5467.6 5469.9 5501.5 5632.8 5646.4 5651.9 5727.9 5545.5 5546.2 5564.4 5572.7 5573.4 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | [#1] ext.html Cycles:180 Average:56283.03 Median:3811.77 stddev:104336.82 (2737.2%) stddev-sans-first:104545.58 05:24:32 INFO - PROCESS | 25163 | Values: 544.9 602.9 612.8 613.2 640.9 30636.2 31117.0 43804.1 45099.8 45360.3 43756.3 47212.8 47228.0 47247.5 47356.0 9074.4 9270.5 9279.5 9281.1 9284.5 7517.7 7722.9 8703.2 10599.1 10884.9 10884.3 10964.3 10964.5 10998.1 10998.1 10950.0 10972.9 11029.4 11043.0 11047.0 10699.9 10768.9 10776.2 10776.7 10779.0 43711.3 44104.8 44148.0 44322.8 44352.6 3758.1 3802.6 3821.6 3846.6 3912.6 31890.8 32106.5 32285.0 32343.5 32548.0 3506.4 3655.7 3695.4 3821.0 3829.7 308803.0 310637.0 311097.0 311698.4 313407.0 61415.4 61495.4 61578.4 61655.4 61685.4 64659.0 64923.0 65019.3 65250.3 65445.0 15436.0 15617.1 15634.1 15640.2 15644.1 10299.7 10892.1 10915.5 10924.1 10933.7 15090.8 17872.6 18287.8 18331.8 19228.1 5949.5 6744.8 6914.2 6959.7 7044.6 889.8 896.8 897.9 902.4 926.9 30389.8 30425.2 30899.7 31188.7 31224.2 1077.7 1079.8 1081.9 1088.5 1124.2 15731.0 16252.3 16260.8 16276.3 16600.0 451818.0 457301.0 457714.0 457763.0 457969.0 49659.3 50923.2 59228.4 60693.0 60765.1 1385.8 1395.3 1411.7 1415.5 1416.3 30790.5 32839.5 32986.2 33060.0 33849.3 53566.0 54252.0 54284.2 54368.5 54380.1 402901.0 404961.0 405629.0 405707.0 406113.0 40638.3 40962.4 41356.0 41684.4 41713.4 45997.3 46576.5 46953.3 47435.8 47448.1 37771.6 39406.4 39420.8 40550.4 40995.4 35384.0 36154.3 36589.8 37953.8 40624.6 37457.4 37737.2 38582.4 38752.6 38758.4 29086.9 32182.8 32711.2 33003.3 33013.8 25275.7 25488.7 25534.1 26062.0 26226.6 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | [#2] jquery.html Cycles:180 Average:34087.37 Median:2827.25 stddev:107027.17 (3785.6%) stddev-sans-first:107297.49 05:24:32 INFO - PROCESS | 25163 | Values: 387.9 423.2 428.5 439.3 443.3 15533.7 19084.1 19647.0 21037.6 21167.4 20235.8 20536.4 21029.5 21074.2 21209.4 5907.3 5925.3 6145.8 6153.3 6158.8 5967.8 6008.5 6014.8 6016.2 6019.3 5799.8 5977.2 5985.2 5997.1 5997.4 5997.9 6033.8 6055.8 6076.5 6080.8 5419.9 5420.1 5465.2 5469.2 5476.8 21590.2 22194.9 22466.7 24043.4 24251.9 2808.0 2809.6 2824.0 2830.5 2877.1 10052.4 10229.9 10319.4 10578.3 10653.0 1826.3 1860.4 1868.3 1884.5 1892.7 81450.6 86041.6 86543.0 88511.6 88928.0 10167.4 11784.6 11935.6 12037.0 12182.6 14510.9 15324.7 15420.0 17322.0 17343.7 8030.3 8079.8 8258.4 8371.2 8379.3 7421.4 7486.2 7505.8 7638.0 7697.2 5132.6 5242.4 5255.9 5327.7 5332.7 2059.2 2110.3 2152.0 2191.4 2224.6 976.8 977.6 978.7 984.4 991.2 20001.0 20023.9 20039.0 20053.2 20110.3 936.9 938.7 980.7 997.7 1007.4 10186.4 10767.4 10820.6 10848.6 10862.6 599338.0 646597.0 665555.0 670619.0 676168.0 88784.6 93118.0 101624.5 103848.5 104129.0 1040.7 1069.8 1101.0 1105.8 1106.6 17372.6 17748.6 17928.7 18512.6 18930.0 41497.6 42058.4 42507.6 43693.0 44059.2 58875.4 58930.4 59888.0 60281.0 61109.7 8210.3 8320.2 8383.3 8503.9 8850.6 10636.7 12500.5 12820.6 13231.7 13493.1 10964.5 11131.1 11226.3 11320.9 11630.5 8490.9 11642.8 11744.6 11791.4 11869.1 8251.2 8729.5 8739.6 8871.1 9038.2 12307.6 12720.9 12930.4 13101.5 13445.9 12507.6 12518.8 12528.2 12692.7 12990.6 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | [#3] mootools.html Cycles:180 Average:5476.09 Median:5042.52 stddev:3139.58 (62.3%) stddev-sans-first:3133.53 05:24:32 INFO - PROCESS | 25163 | Values: 1410.9 1426.3 1436.1 1446.7 1447.5 8232.3 8689.3 8699.2 8701.6 8702.3 8370.8 8518.4 8593.1 8752.6 8759.5 2231.5 2285.6 2288.1 2289.8 2291.1 1879.7 2208.5 2259.6 2260.5 2264.2 1912.8 2168.2 2235.8 2241.6 2265.7 2115.4 2124.3 2125.6 2125.9 2128.9 1934.1 2121.1 2200.2 2207.9 2211.7 8709.7 8781.1 8791.6 8793.4 8797.5 5033.1 5177.5 5181.8 5188.2 5188.3 3066.5 3259.2 3259.6 3280.9 3284.5 1292.2 1297.5 1351.2 1374.1 1382.8 11676.1 11691.4 11772.6 11824.6 11825.6 4021.7 4267.3 4301.5 4309.2 4318.8 9367.8 9378.4 9404.2 9404.3 9420.6 5036.4 5048.6 5078.4 5090.3 5091.2 4593.6 4632.6 4732.2 4740.5 4745.2 3534.5 3628.6 3646.5 3662.8 3678.0 1070.2 1083.0 1085.4 1092.4 1103.9 847.9 863.1 881.4 891.4 896.2 5474.9 5499.6 5513.2 5584.9 5724.6 578.2 580.1 587.9 595.4 606.7 3452.1 3567.4 3646.5 3739.9 3887.9 9935.6 10163.0 10377.8 10438.1 10449.4 10300.1 10347.7 10350.2 10437.5 10465.5 7803.9 7947.2 8045.4 8047.2 8056.2 5452.4 5457.4 5484.9 5516.0 5643.6 9222.9 9244.6 9291.3 9295.0 9437.6 9551.2 9588.0 9666.5 9669.2 9693.9 7019.3 7098.0 7100.1 7101.8 7102.0 7924.9 8048.2 8086.2 8144.1 8145.1 7077.9 7104.1 7159.8 7168.6 7359.3 6943.3 6959.6 6988.6 6998.2 7005.0 6226.2 6241.3 6309.4 6345.8 6415.8 4853.8 4866.1 4926.4 4928.9 5018.3 4890.5 4957.5 4964.2 4992.3 5013.0 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | [#4] prototype.html Cycles:180 Average:4667.36 Median:5241.24 stddev:1360.40 (26.0%) stddev-sans-first:1325.41 05:24:32 INFO - PROCESS | 25163 | Values: 368.6 380.2 383.2 384.4 385.5 5257.2 5268.7 5273.7 5289.8 5300.2 4993.7 5156.9 5206.6 5222.1 5232.2 4140.1 4168.7 4170.2 4174.9 4187.9 4173.3 4184.9 4189.4 4195.9 4207.9 4169.5 4184.2 4187.7 4187.9 4198.7 3852.5 4035.3 4046.0 4055.4 4069.4 3575.0 3580.1 3587.6 3598.7 3606.5 5122.6 5147.4 5149.8 5193.9 5210.5 4615.1 4712.9 4734.0 4751.8 4767.5 5507.6 5527.0 5531.4 5545.2 5549.7 3982.0 3989.6 4015.1 4015.2 4022.6 5574.8 5693.8 5736.9 5750.8 5757.4 4105.0 4129.6 4133.5 4141.3 4142.1 4184.0 4200.5 4216.4 4223.5 4261.1 4619.5 4668.3 4703.5 4760.6 4810.4 5534.2 5545.0 5557.4 5579.4 5585.1 1400.2 1405.9 1410.4 1414.4 1416.8 905.6 974.7 977.9 978.8 987.3 5140.5 5185.8 5187.1 5206.1 5228.7 5563.6 5569.9 5626.7 5638.2 5662.0 4986.4 5069.4 5135.9 5139.0 5221.6 2913.5 2924.0 2944.3 3001.8 3005.2 5878.5 5890.8 5895.6 5905.9 5915.6 5397.8 5486.0 5552.0 5602.4 5682.9 5761.7 5794.5 5900.5 5943.6 5947.9 5658.1 5678.5 5687.4 5696.7 5698.2 5447.4 5570.6 5628.6 5642.2 5683.8 5986.7 6007.6 6018.2 6032.2 6038.4 4128.8 4128.9 4161.8 4166.3 4166.9 5411.1 5697.6 5700.3 5706.8 5718.8 5537.9 5541.2 5545.9 5555.1 5616.2 5250.2 5487.4 5536.3 5543.3 5587.6 5553.3 5556.5 5559.6 5580.9 5583.3 5602.6 5609.4 5635.2 5653.3 5669.5 5215.2 5434.4 5482.5 5498.0 5514.6 05:24:32 INFO - PROCESS | 25163 | 05:24:32 INFO - PROCESS | 25163 | [#5] yui.html Cycles:180 Average:16819.27 Median:4027.02 stddev:20908.46 (519.2%) stddev-sans-first:20932.24 05:24:32 INFO - PROCESS | 25163 | Values: 738.0 763.5 781.9 829.0 836.9 14260.1 15364.6 15440.3 15448.8 15468.3 13627.4 13635.4 13642.1 13642.4 13648.8 787.5 797.5 800.1 812.5 816.4 823.3 825.6 828.6 828.9 829.5 850.4 856.0 857.2 857.6 861.3 853.6 853.9 855.3 858.1 861.1 632.0 632.1 633.0 633.1 633.3 14152.4 14501.1 15053.3 15068.1 15130.0 14322.6 14650.3 14654.3 14829.9 14902.0 11596.2 11928.2 12033.9 12168.2 12528.4 3604.5 3609.9 3668.6 3675.6 3715.7 75762.6 77509.6 78477.6 78628.2 79633.6 11132.7 11328.8 11351.3 11361.0 11394.5 20224.3 20241.0 20249.3 20288.4 20307.9 7196.5 7378.2 7543.5 7558.0 7713.8 3852.5 3918.6 4010.8 4043.3 4121.4 3622.7 3703.9 3727.2 3731.7 3753.0 595.4 611.0 615.1 623.5 625.3 649.4 650.7 678.4 681.1 695.5 9344.4 9478.2 9686.4 9813.6 9997.8 662.9 689.4 689.8 696.8 704.9 4449.3 4657.5 4740.6 5196.9 5267.4 70601.0 72024.6 72731.6 72900.0 75464.0 50931.0 57314.7 62091.0 62653.7 66795.0 56969.4 57172.7 57226.4 57457.0 57564.4 9967.6 10229.8 10460.5 10507.8 10607.9 37986.8 39182.0 39472.0 39539.4 40935.0 51564.7 53890.0 54219.5 54416.0 55729.4 12987.3 13262.3 13290.6 13560.9 13764.8 13470.5 13738.6 13744.9 14604.3 14789.1 14074.8 14292.5 14518.1 14842.7 14983.0 13661.5 13730.1 13966.4 14145.0 14419.0 13220.3 13422.2 13555.5 13618.7 14172.3 11205.8 11301.8 11531.6 11829.7 11961.9 10114.5 10122.0 10261.4 11123.3 11510.7 05:24:32 INFO - PROCESS | 25163 | -------- Summary: end -------- 05:24:32 INFO - PROCESS | 25163 | 05:24:33 INFO - TEST-INFO | 25163: exit 0 05:24:33 INFO - TEST-OK | dromaeo_css | took 1117119ms 05:24:33 INFO - TEST-START | kraken 05:24:33 INFO - operating with platform_type : linux_ 05:24:33 INFO - Initialising browser for kraken test... 05:24:33 INFO - TEST-INFO | started process 25482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0fl7EH/profile http://localhost:33229/getInfo.html) 05:24:36 INFO - PROCESS | 25482 | Iterating over profile /tmp/tmp0fl7EH/profile 05:24:37 INFO - PROCESS | 25482 | __metrics Screen width/height:1600/1200 05:24:37 INFO - PROCESS | 25482 | colorDepth:24 05:24:37 INFO - PROCESS | 25482 | Browser inner width/height: 1024/697 05:24:37 INFO - PROCESS | 25482 | __metrics 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/secmod.db = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/key3.db = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/prefs.js = Fri Feb 17 2017 05:05:15 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/compatibility.ini = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/permissions.sqlite = Fri Feb 17 2017 05:24:35 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/gmp = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/sessionCheckpoints.json = Fri Feb 17 2017 05:24:37 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/addons.json = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/directoryLinks.json = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/safebrowsing = Fri Feb 17 2017 05:24:37 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/content-prefs.sqlite = Fri Feb 17 2017 05:24:35 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/places.sqlite = Fri Feb 17 2017 05:24:37 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/crashes = Fri Feb 17 2017 05:24:33 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/pluginreg.dat = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | Stat failure 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/places.sqlite-shm = Fri Feb 17 2017 05:24:35 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/places.sqlite-wal = Fri Feb 17 2017 05:24:37 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/frequencyCap.json = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/bookmarkbackups = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/cache2 = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/minidumps = Fri Feb 17 2017 05:24:33 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/thumbnails = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/.parentlock = Fri Feb 17 2017 05:24:33 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/blocklist.xml = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/extensions.ini = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/user.js = Fri Feb 17 2017 05:24:33 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/cert8.db = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/startupCache = Fri Feb 17 2017 05:24:33 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/extensions = Fri Feb 17 2017 05:24:34 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/localstore.rdf = Fri Feb 17 2017 05:05:15 GMT-0800 (PST) 05:24:37 INFO - PROCESS | 25482 | No birth date. Using mtime. 05:24:37 INFO - PROCESS | 25482 | Using date: /tmp/tmp0fl7EH/profile/extensions.json = Fri Feb 17 2017 05:24:36 GMT-0800 (PST) 05:24:38 INFO - PROCESS | 25482 | 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" 05:24:38 INFO - PROCESS | 25482 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:39 INFO - PROCESS | 25482 | console.error: PushService: 05:24:39 INFO - PROCESS | 25482 | stateChangeProcessEnqueue: Error transitioning state 05:24:39 INFO - PROCESS | 25482 | UnknownError 05:24:39 INFO - TEST-INFO | 25482: exit 0 05:24:39 INFO - Browser initialized. 05:24:39 INFO - Running cycle 1/1 for kraken test... 05:24:39 INFO - TEST-INFO | started process 25576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0fl7EH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:51 INFO - PROCESS | 25576 | RSS: Main: 142290944 05:24:51 INFO - PROCESS | 25576 | 05:24:54 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33229/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:54 INFO - PROCESS | 25576 | RSS: Main: 198737920 05:24:54 INFO - PROCESS | 25576 | 05:24:57 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33229/tests/kraken/driver.html?testName=audio-dft) 05:24:58 INFO - PROCESS | 25576 | RSS: Main: 177123328 05:24:58 INFO - PROCESS | 25576 | 05:25:01 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33229/tests/kraken/driver.html?testName=audio-fft) 05:25:01 INFO - PROCESS | 25576 | RSS: Main: 259055616 05:25:01 INFO - PROCESS | 25576 | 05:25:03 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33229/tests/kraken/driver.html?testName=audio-oscillator) 05:25:04 INFO - PROCESS | 25576 | RSS: Main: 170905600 05:25:04 INFO - PROCESS | 25576 | 05:25:06 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:07 INFO - PROCESS | 25576 | RSS: Main: 174026752 05:25:07 INFO - PROCESS | 25576 | 05:25:10 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33229/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:10 INFO - PROCESS | 25576 | RSS: Main: 214110208 05:25:10 INFO - PROCESS | 25576 | 05:25:14 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33229/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:14 INFO - PROCESS | 25576 | RSS: Main: 213716992 05:25:14 INFO - PROCESS | 25576 | 05:25:17 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33229/tests/kraken/driver.html?testName=json-parse-financial) 05:25:18 INFO - PROCESS | 25576 | RSS: Main: 212635648 05:25:18 INFO - PROCESS | 25576 | 05:25:19 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:20 INFO - PROCESS | 25576 | RSS: Main: 174854144 05:25:20 INFO - PROCESS | 25576 | 05:25:21 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:22 INFO - PROCESS | 25576 | RSS: Main: 182251520 05:25:22 INFO - PROCESS | 25576 | 05:25:24 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:25 INFO - PROCESS | 25576 | RSS: Main: 190238720 05:25:25 INFO - PROCESS | 25576 | 05:25:27 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:28 INFO - PROCESS | 25576 | RSS: Main: 199135232 05:25:28 INFO - PROCESS | 25576 | 05:25:31 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:32 INFO - PROCESS | 25576 | RSS: Main: 196308992 05:25:32 INFO - PROCESS | 25576 | 05:25:33 INFO - PROCESS | 25576 | Cycle 1(1): loaded http://localhost:33229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33229/tests/kraken/driver.html?testName=ai-astar) 05:25:34 INFO - PROCESS | 25576 | RSS: Main: 248180736 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | __start_tp_report 05:25:34 INFO - PROCESS | 25576 | _x_x_mozilla_page_load 05:25:34 INFO - PROCESS | 25576 | _x_x_mozilla_page_load_details 05:25:34 INFO - PROCESS | 25576 | |i|pagename|runs| 05:25:34 INFO - PROCESS | 25576 | |0;ai-astar;111;112;111;113;112;112;112;111;112;113 05:25:34 INFO - PROCESS | 25576 | |1;audio-beat-detection;149;122;123;156;122;121;127;121;152;121 05:25:34 INFO - PROCESS | 25576 | |2;audio-dft;161;161;161;162;161;162;162;163;162;163 05:25:34 INFO - PROCESS | 25576 | |3;audio-fft;74;77;75;109;80;76;77;76;95;76 05:25:34 INFO - PROCESS | 25576 | |4;audio-oscillator;84;82;116;84;82;83;96;82;83;107 05:25:34 INFO - PROCESS | 25576 | |5;imaging-gaussian-blur;113;113;113;114;114;113;114;113;112;113 05:25:34 INFO - PROCESS | 25576 | |6;imaging-darkroom;146;147;147;147;147;147;146;146;146;147 05:25:34 INFO - PROCESS | 25576 | |7;imaging-desaturate;97;96;95;96;96;96;96;96;96;96 05:25:34 INFO - PROCESS | 25576 | |8;json-parse-financial;55;54;55;54;55;56;56;55;54;54 05:25:34 INFO - PROCESS | 25576 | |9;json-stringify-tinderbox;65;64;63;63;63;67;63;63;63;64 05:25:34 INFO - PROCESS | 25576 | |10;stanford-crypto-aes;84;94;84;92;90;84;89;83;83;92 05:25:34 INFO - PROCESS | 25576 | |11;stanford-crypto-ccm;139;135;129;130;132;136;128;138;170;142 05:25:34 INFO - PROCESS | 25576 | |12;stanford-crypto-pbkdf2;173;175;171;174;175;174;176;173;179;170 05:25:34 INFO - PROCESS | 25576 | |13;stanford-crypto-sha256-iterative;65;64;64;64;63;65;64;63;63;63 05:25:34 INFO - PROCESS | 25576 | __end_tp_report 05:25:34 INFO - PROCESS | 25576 | __start_cc_report 05:25:34 INFO - PROCESS | 25576 | _x_x_mozilla_cycle_collect,1718 05:25:34 INFO - PROCESS | 25576 | __end_cc_report 05:25:34 INFO - PROCESS | 25576 | __startTimestamp1487337934026__endTimestamp 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | ------- Summary: start ------- 05:25:34 INFO - PROCESS | 25576 | Number of tests: 14 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 05:25:34 INFO - PROCESS | 25576 | Values: 111.0 112.0 111.0 113.0 112.0 112.0 112.0 111.0 112.0 113.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:125.00 stddev:14.65 (11.7%) stddev-sans-first:14.08 05:25:34 INFO - PROCESS | 25576 | Values: 149.0 122.0 123.0 156.0 122.0 121.0 127.0 121.0 152.0 121.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 05:25:34 INFO - PROCESS | 25576 | Values: 161.0 161.0 161.0 162.0 161.0 162.0 162.0 163.0 162.0 163.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.50 stddev:11.40 (14.9%) stddev-sans-first:11.77 05:25:34 INFO - PROCESS | 25576 | Values: 74.0 77.0 75.0 109.0 80.0 76.0 77.0 76.0 95.0 76.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:83.00 stddev:12.31 (14.8%) stddev-sans-first:12.87 05:25:34 INFO - PROCESS | 25576 | Values: 84.0 82.0 116.0 84.0 82.0 83.0 96.0 82.0 83.0 107.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 05:25:34 INFO - PROCESS | 25576 | Values: 113.0 113.0 113.0 114.0 114.0 113.0 114.0 113.0 112.0 113.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:25:34 INFO - PROCESS | 25576 | Values: 146.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 146.0 147.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 05:25:34 INFO - PROCESS | 25576 | Values: 97.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.79 (1.4%) stddev-sans-first:0.83 05:25:34 INFO - PROCESS | 25576 | Values: 55.0 54.0 55.0 54.0 55.0 56.0 56.0 55.0 54.0 54.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:63.50 stddev:1.32 (2.1%) stddev-sans-first:1.32 05:25:34 INFO - PROCESS | 25576 | Values: 65.0 64.0 63.0 63.0 63.0 67.0 63.0 63.0 63.0 64.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:89.50 stddev:4.33 (4.8%) stddev-sans-first:4.40 05:25:34 INFO - PROCESS | 25576 | Values: 84.0 94.0 84.0 92.0 90.0 84.0 89.0 83.0 83.0 92.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:137.00 stddev:12.18 (8.9%) stddev-sans-first:12.91 05:25:34 INFO - PROCESS | 25576 | Values: 139.0 135.0 129.0 130.0 132.0 136.0 128.0 138.0 170.0 142.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:174.50 stddev:2.54 (1.5%) stddev-sans-first:2.67 05:25:34 INFO - PROCESS | 25576 | Values: 173.0 175.0 171.0 174.0 175.0 174.0 176.0 173.0 179.0 170.0 05:25:34 INFO - PROCESS | 25576 | 05:25:34 INFO - PROCESS | 25576 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 05:25:34 INFO - PROCESS | 25576 | Values: 65.0 64.0 64.0 64.0 63.0 65.0 64.0 63.0 63.0 63.0 05:25:34 INFO - PROCESS | 25576 | -------- Summary: end -------- 05:25:34 INFO - PROCESS | 25576 | 05:25:35 INFO - TEST-INFO | 25576: exit 0 05:25:35 INFO - TEST-OK | kraken | took 61365ms 05:25:35 INFO - SUITE-END | took 1178s 05:25:35 INFO - Completed test suite (00:19:38) 05:25:35 INFO - javascript benchmark 05:25:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7133.747339611102, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3148.496240601504, 3189.394015137122, 3192.2976945072082, 3212.710856022959, 3223.822689752064, 5088.18589025756, 5229.267902493654, 5259.737013149342, 5338.27933229014, 5345.465453454657, 5235.921461178076, 5241.5806735461165, 5328.0, 5355.678659280446, 5393.217983392416, 4579.610733087691, 4741.668083234175, 4745.715257084577, 4780.474147843752, 4903.975480122613, 4894.412670479547, 4936.128610691275, 4953.058918805434, 4954.7027178369235, 4957.107720610289, 4642.9071418571775, 4660.067986402699, 4661.906761864744, 4704.764761761932, 4817.507307202268, 4694.788734506956, 4738.928916066262, 4740.123077230689, 4741.0, 4807.038592281558, 4701.529847015306, 4715.669903106784, 4723.669343145981, 4757.00102978375, 4778.80884764609, 5256.842294731164, 5258.0535503609335, 5270.183121616155, 5282.7622756976025, 5292.0, 5517.972410137963, 5538.113901775737, 5552.833414997556, 5641.52047075995, 5689.516391106761, 5623.465770751772, 5671.461211184931, 5676.659400435987, 5677.148427735831, 5678.403767604384, 5317.335333083364, 5319.946800532022, 5323.733813309319, 5325.440828712968, 5358.678479291255, 5875.676837773878, 5931.436513531207, 5986.132010858489, 5990.131430942489, 5992.0712289595185, 5427.0, 5453.15476101205, 5463.562914966793, 5713.5714821388565, 5751.022326204556, 5637.802676906289, 5645.774169033275, 5645.830625081254, 5649.774009039593, 5651.321841379061, 5605.271314729059, 5610.1584762285165, 5670.177824215548, 5683.658980461186, 5694.316681998187, 5784.074548072373, 5786.508146807569, 5789.247397838339, 5791.5656325775735, 5792.218050563204, 4257.723247988871, 4281.336392859112, 4431.180231657154, 4440.2007638625355, 4453.888652783641, 3982.123932734794, 3992.8802135935966, 3995.300822356134, 4000.8399664013114, 4015.9397609035886, 5410.648307859977, 5419.593530485151, 5419.918701219485, 5420.972895135499, 5456.972715136479, 5543.695096769636, 5580.135079062752, 5582.525485333711, 5593.74828132735, 5655.066913959151, 5295.4439783822145, 5308.522232999048, 5311.362636483646, 5314.548263397577, 5361.678299301976, 4945.802167913315, 5033.1695270280725, 5041.798328066836, 5042.924356134661, 5046.0, 6080.695965201722, 6086.60437071589, 6088.452039316483, 6090.390960903874, 6132.7546898124465, 5763.884722305615, 5816.680082595498, 5824.70876456167, 5826.863761566454, 5831.533477321719, 5785.971070144622, 5799.217105690678, 5849.093390524476, 5932.733027013708, 5933.169356290036, 5603.495685388335, 5611.270534830446, 5613.494785469327, 5614.775408983595, 5619.943800562106, 5674.404187560247, 5674.574406919415, 5677.659340439587, 5678.574106941914, 5679.261695979496, 5512.118061110203, 5560.7219639018695, 5565.471280228372, 5652.095664693629, 5656.349519805275, 5385.461453854583, 5428.945710542844, 5429.755660995185, 5633.971830140823, 5644.096944488862, 5458.972705136449, 5466.480684334982, 5467.125259958388, 5467.58993075513, 5469.890602188014, 5501.504864562048, 5632.802851900164, 5646.407127251579, 5651.915221271602, 5727.942720572741, 5545.528630066489, 5546.195801608825, 5564.360098588552, 5572.693501857437, 5573.414791446839], "value": 5252.678443751266, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.9428109467633, 602.9216201893753, 612.8039027511195, 613.1875265273512, 640.9262934762504, 30636.24273815356, 31117.0, 43804.12391752167, 45099.77450112753, 45360.3195952061, 43756.3436548452, 47212.76393618036, 47228.0, 47247.52752472474, 47356.0, 9074.410163339395, 9270.536473176347, 9279.536023198847, 9281.118293762082, 9284.489353085577, 7517.736879209228, 7722.884156737653, 8703.216710496054, 10599.09907657846, 10884.891151088506, 10884.292520986111, 10964.342139471617, 10964.451777411137, 10998.06516446103, 10998.12015038799, 10950.0, 10972.890271097307, 11029.44852757367, 11043.0, 11047.0, 10699.946500267526, 10768.946155269174, 10776.19178561611, 10776.73058173544, 10779.0, 43711.344329835076, 44104.77947610273, 44148.0, 44322.778386108184, 44352.556474435165, 3758.1356288053903, 3802.5627052888995, 3821.6433564691306, 3846.557645870733, 3912.647861692433, 31890.840545797353, 32106.518402223985, 32285.0, 32343.51484727731, 32548.0, 3506.3513250048823, 3655.670989610948, 3695.4456831475227, 3820.9808950955344, 3829.7127715421457, 308803.0, 310637.0, 311097.0, 311698.44150779326, 313407.0, 61415.38584614186, 61495.38504614986, 61578.38421615816, 61655.38344616586, 61685.364049033175, 64659.0, 64923.0, 65019.34980650133, 65250.34749652538, 65445.0, 15436.0, 15617.14105724196, 15634.107690077371, 15640.2179891005, 15644.139572323631, 10299.691009269734, 10892.074173695166, 10915.454227288605, 10924.071453926505, 10933.671989840317, 15090.773638395432, 17872.642547148986, 18287.817121828877, 18331.81668183328, 19228.134733937008, 5949.524038076944, 6744.83137921546, 6914.239433662293, 6959.6941452809515, 7044.577325360495, 889.8042430665244, 896.7533928169805, 897.8574987543598, 902.4044130873592, 926.8702381666573, 30389.848050759603, 30425.23936901595, 30899.69100309013, 31188.68811311858, 31224.21939451532, 1077.7467295185625, 1079.7570546626946, 1081.9350838949688, 1088.5482524752297, 1124.2299025167786, 15731.0, 16252.268647910872, 16260.75608865868, 16276.26756795947, 16600.027988244965, 451818.0, 457301.0, 457714.0, 457763.0, 457969.0, 49659.25511117336, 50923.23615145776, 59228.40771592315, 60693.0, 60765.08852367218, 1385.757492438819, 1395.295375835217, 1411.7176564687104, 1415.490423447558, 1416.2776983738368, 30790.53675705983, 32839.507407389385, 32986.17534561655, 33060.0, 33849.3230135391, 53566.0, 54252.0, 54284.185737213185, 54368.45631543634, 54380.11488334249, 402901.0, 404961.0, 405629.0, 405707.0, 406113.0, 40638.26277350384, 40962.38556421596, 41356.0, 41684.374734378405, 41713.37429938493, 45997.310040348755, 46576.50676199231, 46953.29570056384, 47435.76282118567, 47448.05103897972, 37771.62228377681, 39406.408903867035, 39420.80289598648, 40550.39174412445, 40995.38506922458, 35384.0, 36154.276914461036, 36589.81705091458, 37953.81023094867, 40624.59375406208, 37457.4381384274, 37737.245255095295, 38582.4212636805, 38752.6124738749, 38758.4186237201, 29086.854565727037, 32182.839085805357, 32711.18222044468, 33003.339933201685, 33013.83493082519, 25275.74724252734, 25488.74511254938, 25534.106306279933, 26062.0, 26226.60660090062], "value": 20009.40157342942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.88169608269504, 423.208322137346, 428.45800062920404, 439.30808975863016, 443.2929477483413, 15533.689326213484, 19084.141213645402, 19646.99999999998, 21037.58067822201, 21167.364979050653, 20235.797642023575, 20536.38390848273, 21029.474263143427, 21074.15703371863, 21209.36371908845, 5907.261592300963, 5925.259342582177, 6145.8156255312415, 6153.292371377282, 6158.815235542941, 5967.791127310545, 6008.549358798085, 6014.75940962361, 6016.157737916695, 6019.277686677605, 5799.768009279624, 5977.193078934331, 5985.16207730918, 5997.100434934751, 5997.37027612101, 5997.940020599782, 6033.849153771147, 6055.818325450244, 6076.4834989025985, 6080.787172448965, 5419.945800541984, 5420.051490989101, 5465.180222966547, 5469.206964990054, 5476.780928762844, 21590.24434144813, 22194.88902555493, 22466.663000055014, 24043.39891502709, 24251.87874060636, 2807.971920280812, 2809.634747482814, 2823.957640635392, 2830.547112461996, 2877.079334612925, 10052.418453283015, 10229.948850255701, 10319.43243121628, 10578.312409693346, 10653.041226289593, 1826.3425166939892, 1860.4186441524596, 1868.2620364955767, 1884.4629280654947, 1892.6971684530408, 81450.59274703648, 86041.56979215064, 86543.0, 88511.55744221302, 88927.99999999936, 10167.440790756431, 11784.58753943625, 11935.582254621047, 12037.0, 12182.573609923611, 14510.85489145095, 15324.693506129815, 15420.0, 17322.0, 17343.739843902353, 8030.277275045274, 8079.798005049921, 8258.398604439582, 8371.246587807127, 8379.329653627696, 7421.406287496988, 7486.176520582731, 7505.812354691177, 7637.961810190914, 7697.153313135551, 5132.589392848563, 5242.397124330674, 5255.947440525622, 5327.706976116351, 5332.733363331818, 2059.1969132038516, 2110.314147901938, 2152.0, 2191.386411804697, 2224.566209589115, 976.7851072763981, 977.5991843344337, 978.7161723100363, 984.3601658921758, 991.1723710701501, 20000.999950002733, 20023.899880500794, 20039.0, 20053.197872084955, 20110.296139635044, 936.8735220745112, 938.7324612485409, 980.7352014956065, 997.6607953295769, 1007.4459047523833, 10186.439745814052, 10767.3539587625, 10820.621278255381, 10848.566057357775, 10862.619808306672, 599338.0, 646597.0, 665555.0, 670619.0, 676168.0, 88784.55607722049, 93118.0, 101624.49187754162, 103848.48075759572, 104129.0, 1040.7086015915556, 1069.7860427914604, 1101.0365929811414, 1105.8443926097248, 1106.6458733205297, 17372.565685857953, 17748.645027099126, 17928.731069033714, 18512.629747405244, 18930.0, 41497.58502415058, 42058.36912446377, 42507.57492425036, 43693.0, 44059.205494654016, 58875.41124588699, 58930.41069589249, 59888.0, 60281.0, 61109.69445152746, 8210.3021243195, 8320.16798320163, 8383.329333653171, 8503.914960850312, 8850.64597416122, 10636.680899573024, 12500.499980001063, 12820.551280705131, 13231.66920826987, 13493.122947008413, 10964.451777411257, 11131.109511239243, 11226.270292430965, 11320.943395282971, 11630.53477860876, 8490.872636910328, 11642.76714465723, 11744.647660570197, 11791.35147566892, 11869.050475962074, 8251.174882511701, 8729.476231426135, 8739.606717697847, 8871.112888711077, 9038.231750301298, 12307.630771076882, 12720.883383570714, 12930.353482325658, 13101.541446049343, 13445.932770336085, 12507.56223532172, 12518.812217816558, 12528.185667931555, 12692.682682932631, 12990.610281691565], "value": 9581.082765271563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.8800751936085, 1426.3153686230603, 1436.0737324425745, 1446.6600348918003, 1447.5078473319072, 8232.259096681297, 8689.348298877578, 8699.217070463657, 8701.608427620764, 8702.26030787383, 8370.790730231747, 8518.40371174018, 8593.054763975957, 8752.649894004233, 8759.51822649754, 2231.5313784105315, 2285.5543169082034, 2288.107638021173, 2289.7595752446005, 2291.140822191678, 1879.7086451600023, 2208.536207396449, 2259.6271615183473, 2260.480089579398, 2264.1509433962265, 1912.80871912809, 2168.2086038595876, 2235.7540670526228, 2241.6301310283784, 2265.728112626479, 2115.4394085567337, 2124.2671278408925, 2125.596136734031, 2125.8937053147433, 2128.861059333257, 1934.071645610115, 2121.140937920145, 2200.2409168836725, 2207.8896055197174, 2211.6793065005486, 8709.651613935435, 8781.077986811357, 8791.604377803014, 8793.449899529129, 8797.516136612483, 5033.144365457881, 5177.48225177483, 5181.844544663666, 5188.22176673502, 5188.273641690166, 3066.540018997146, 3259.2014956335847, 3259.6251431085493, 3280.9015729527914, 3284.5237440571223, 1292.1536393662166, 1297.4810075969688, 1351.2162945491689, 1374.0519041861085, 1382.7549423270564, 11676.124290678148, 11691.356975366352, 11772.64682059531, 11824.586139485076, 11825.645230643095, 4021.6581590564547, 4267.338562522814, 4301.483821941387, 4309.159713855768, 4318.848840290575, 9367.812643747087, 9378.43729376226, 9404.200642945427, 9404.341696081345, 9420.576074076545, 5036.445990941067, 5048.596112311006, 5078.4413714491375, 5090.26191202281, 5091.210862316347, 4593.609543188866, 4632.60622847055, 4732.219183834698, 4740.525947405232, 4745.240761478147, 3534.5228394166456, 3628.63713628635, 3646.4712616670436, 3662.8351724171944, 3678.0, 1070.1866581398037, 1082.967510974672, 1085.3542142425244, 1092.3773449134076, 1103.87857335693, 847.9067302596709, 863.0808189278398, 881.352206128495, 891.4205766251988, 896.1755185229605, 5474.863128421742, 5499.587530935196, 5513.228148059275, 5584.86037849057, 5724.627899186539, 578.2338401617873, 580.0979476913355, 587.8853623543368, 595.4016213705198, 606.6602702486622, 3452.1369657585606, 3567.3935430976794, 3646.4712616670436, 3739.8691045813266, 3887.922241555153, 9935.552900119512, 10163.0, 10377.792444151075, 10438.112760415297, 10449.425281609585, 10300.124489418333, 10347.689569312932, 10350.171986241083, 10437.478126093665, 10465.529051192714, 7803.882941755878, 7947.245011723877, 8045.356371490267, 8047.155048719917, 8056.194380561897, 5452.400235974119, 5457.372402173642, 5484.917726234189, 5516.034693928626, 5643.604947653625, 9222.861657075015, 9244.583993720433, 9291.303152263472, 9295.0, 9437.622495100119, 9551.188149007412, 9588.0, 9666.516674166403, 9669.178119859891, 9693.933667296733, 7019.333163349474, 7098.041764361951, 7100.076989991475, 7101.751438699621, 7102.041224434635, 7924.881126782988, 8048.195180481905, 8086.231807978233, 8144.104148543537, 8145.144759800136, 7077.858442831218, 7104.147502299757, 7159.821004474929, 7168.569885806868, 7359.337659610661, 6943.340382663639, 6959.61722105289, 6988.580685158907, 6998.230194678479, 7004.964975175091, 6226.1905952225925, 6241.31345551998, 6309.432151106423, 6345.841353966003, 6415.845147873428, 4853.757312134449, 4866.075445665454, 4926.408830940167, 4928.852134435972, 5018.297438358706, 4890.510948905081, 4957.4794646561595, 4964.180910149785, 4992.2511623256805, 5013.047520971004], "value": 4336.615025752946, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.6203210692987, 380.2129591745088, 383.1780830119394, 384.41952651496234, 385.54698229580254, 5257.185136303879, 5268.6575372600755, 5273.6572122811995, 5289.841304760854, 5300.204969254613, 4993.650444468889, 5156.948430515694, 5206.557442617382, 5222.120036786754, 5232.241325007872, 4140.130572579762, 4168.749875007494, 4170.249355116078, 4174.895627609319, 4187.937180942288, 4173.309809527132, 4184.8535301264465, 4189.413482112492, 4195.85314513992, 4207.894802629929, 4169.457970463851, 4184.225918205127, 4187.74873507589, 4187.937180942288, 4198.677416613772, 3852.5376954765466, 4035.3341698619693, 4045.9797701011603, 4055.35114381699, 4069.409935559357, 3575.0, 3580.122869896892, 3587.5694916609914, 3598.7300952428673, 3606.459031145323, 5122.590192784605, 5147.4080480744815, 5149.845504634867, 5193.922091168635, 5210.525000624711, 4615.053913947666, 4712.9057418851435, 4734.0, 4751.809927602892, 4767.4994125617, 5507.642003269774, 5526.972365138189, 5531.419200983919, 5545.225527831096, 5549.694766787825, 3981.9601803981877, 3989.581093985119, 4015.072765072752, 4015.1768887378016, 4022.5776293489057, 5574.832755017356, 5693.807883964662, 5736.913946290809, 5750.79872204475, 5757.424257574251, 4105.0, 4129.566395528486, 4133.503979522477, 4141.275276826544, 4142.08874047709, 4183.97908010458, 4200.4539409876525, 4216.409702641633, 4223.5142958559545, 4261.147770445923, 4619.491855895849, 4668.276417155346, 4703.529647035269, 4760.619150467955, 4810.436608102381, 5534.169874518854, 5545.0, 5557.416471270459, 5579.437757427927, 5585.078462053716, 1400.236870905359, 1405.8612523855709, 1410.3864818803854, 1414.429982719181, 1416.8441471438134, 905.5653286422554, 974.7319487141093, 977.9022097790164, 978.8091322192104, 987.3039507147587, 5140.485951404904, 5185.818496352609, 5187.06632806091, 5206.062908676475, 5228.686278823283, 5563.554915606742, 5569.860753481195, 5626.662400255997, 5638.182463542763, 5662.009148399014, 4986.3794266863415, 5069.417017043013, 5135.948640513622, 5139.0, 5221.556167725786, 2913.490139225627, 2923.956140657892, 2944.2628848979207, 3001.8292865782364, 3005.2419334689016, 5878.470937615635, 5890.764369425261, 5895.64626122434, 5905.852353691193, 5915.639402937349, 5397.784088636489, 5485.9725701371235, 5552.0, 5602.431319230622, 5682.886342273131, 5761.654300741969, 5794.50746686528, 5900.498457630978, 5943.613665111754, 5947.895951793249, 5658.122990936246, 5678.5457163426, 5687.43125687428, 5696.715164241688, 5698.1737648041635, 5447.400785913625, 5570.554355651457, 5628.60599758013, 5642.23829782974, 5683.788659407422, 5986.730597123227, 6007.605702777916, 6018.247719035121, 6032.1856549365275, 6038.396160383926, 4128.8348466061025, 4128.91742165161, 4161.812718427617, 4166.312558427825, 4166.8749937501925, 5411.107167317348, 5697.6296540724725, 5700.25896633435, 5706.800261990977, 5718.828435146952, 5537.861553461195, 5541.224228607917, 5545.861353466195, 5555.111182210989, 5616.185653080199, 5250.238715386324, 5487.396386397573, 5536.307961504812, 5543.307086614173, 5587.552995760248, 5553.305836770403, 5556.499915007507, 5559.610827242216, 5580.9071598150695, 5583.330000399978, 5602.5517958562405, 5609.439056094521, 5635.239242702182, 5653.265075540318, 5669.489745922887, 5215.217717342429, 5434.402215756343, 5482.451754824486, 5497.955388476177, 5514.641548299348], "value": 4247.720102254083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.9852402951944, 763.4922776353728, 781.8827175923615, 828.9626966786495, 836.9079401265859, 14260.144391336527, 15364.61588460289, 15440.305186266616, 15448.845511544909, 15468.303926323315, 13627.386767595483, 13635.386407611657, 13642.113262637946, 13642.386092625831, 13648.795268070988, 787.4920676163899, 797.4776521378492, 800.0719165767711, 812.4718932693767, 816.4448175240834, 823.3495538524559, 825.5665775467868, 828.5939889454185, 828.9129641387658, 829.5437509369823, 850.4004676702909, 855.9743207703748, 857.1899554920572, 857.6097875466679, 861.3023451004675, 853.624405261686, 853.9316854651644, 855.2815634866711, 858.0604238358987, 861.0958493581714, 632.0393002635967, 632.0866348126958, 633.0472638678805, 633.0820310549675, 633.3128555517269, 14152.36314365856, 14501.129932203996, 15053.322600483005, 15068.095914245181, 15130.0, 14322.570322890226, 14650.340734666966, 14654.267286635626, 14829.925850370679, 14902.0, 11596.246243994114, 11928.165028448013, 12033.93983030088, 12168.209066410744, 12528.436220370015, 3604.4953706481115, 3609.8736544221088, 3668.633136686337, 3675.558932928039, 3715.6841668458214, 75762.57826558998, 77509.61245193794, 78477.60761196158, 78628.21371786266, 79633.60183199047, 11132.721681958015, 11328.830067548994, 11351.262167959056, 11361.0, 11394.487248073856, 20224.292149774687, 20241.0, 20249.291274805604, 20288.391348259574, 20307.898460507604, 7196.532225405332, 7378.188399276075, 7543.509671871311, 7557.962210188914, 7713.7685869424, 3852.499175107218, 3918.6277303656107, 4010.7593544387432, 4043.2722110020477, 4121.443605113272, 3622.7464077514846, 3703.9074023149096, 3727.1800203955086, 3731.6828069613844, 3753.0, 595.3867516458055, 610.9938900611025, 615.123449085061, 623.4762799248653, 625.343389441085, 649.357136434926, 650.6714109374735, 678.4165617568887, 681.1383599746325, 695.4922906278344, 9344.439333639868, 9478.241740660731, 9686.353319935628, 9813.607455701835, 9997.750056248651, 662.8773676869795, 689.4002218070211, 689.8137502874196, 696.8258484453712, 704.8625518023936, 4449.28811390176, 4657.510961349009, 4740.597049250852, 5196.948030519647, 5267.394249661338, 70601.0, 72024.63987680028, 72731.63634181795, 72900.0, 75464.0, 50931.0, 57314.7134264326, 62091.0, 62653.68673156696, 66795.0, 56969.43030569641, 57172.71413642905, 57226.427735722944, 57457.0, 57564.424355756746, 9967.551460184164, 10229.795404092025, 10460.476976151314, 10507.789844203226, 10607.94696026515, 37986.810065949496, 39182.0, 39472.0, 39539.42993705515, 40935.0, 51564.74217628888, 53890.0, 54219.45780542144, 54416.0, 55729.44270557242, 12987.285699286253, 13262.270575118075, 13290.601281961557, 13560.93219533896, 13764.793528097287, 13470.463187520569, 13738.587842364745, 13744.931275343559, 14604.26978651042, 14789.11265324084, 14074.84138288243, 14292.499762508261, 14518.128912265298, 14842.703145937237, 14983.0, 13661.482140992097, 13730.1426115901, 13966.37151328213, 14145.0, 14419.0, 13220.272884991418, 13422.194668319931, 13555.525556605471, 13618.727625447238, 14172.291385430894, 11205.831912521156, 11301.773964520828, 11531.628372225, 11829.70425739329, 11961.940190298992, 10114.494275286354, 10122.0, 10261.384316941007, 11123.27698699582, 11510.654680359603], "value": 7104.313639942536, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 111.0, 113.0, 112.0, 112.0, 112.0, 111.0, 112.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 123.0, 156.0, 122.0, 121.0, 127.0, 121.0, 152.0, 121.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 163.0, 162.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 109.0, 80.0, 76.0, 77.0, 76.0, 95.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 116.0, 84.0, 82.0, 83.0, 96.0, 82.0, 83.0, 107.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 112.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 55.0, 56.0, 56.0, 55.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 63.0, 63.0, 67.0, 63.0, 63.0, 63.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 94.0, 84.0, 92.0, 90.0, 84.0, 89.0, 83.0, 83.0, 92.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 135.0, 129.0, 130.0, 132.0, 136.0, 128.0, 138.0, 170.0, 142.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 171.0, 174.0, 175.0, 174.0, 176.0, 173.0, 179.0, 170.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 63.0, 65.0, 64.0, 63.0, 63.0, 63.0], "value": 63.8, "unit": "score"}]}]} 05:25:35 INFO - Return code: 0 05:25:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:25:35 ERROR - # TBPL SUCCESS # 05:25:35 INFO - Running post-action listener: _resource_record_post_action 05:25:35 INFO - [mozharness: 2017-02-17 13:25:35.346165Z] Finished run-tests step (success) 05:25:35 INFO - Running post-run listener: _resource_record_post_run 05:25:35 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 370536448; Read time: 0; Write time: 244276 05:25:35 INFO - TinderboxPrint: CPU usage
13.1% 05:25:35 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:25:35 INFO - TinderboxPrint: I/O write bytes / time
370,536,448 / 244,276 05:25:35 INFO - TinderboxPrint: CPU idle
8,288.8 (86.9%) 05:25:35 INFO - TinderboxPrint: CPU user
1,203.3 (12.6%) 05:25:35 INFO - TinderboxPrint: Swap in / out
0 / 0 05:25:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 116518912; Read time: 0; Write time: 103008 05:25:35 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 254017536; Read time: 0; Write time: 141268 05:25:35 INFO - Running post-run listener: _upload_blobber_files 05:25:35 INFO - Blob upload gear active. 05:25:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:35 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 05:25:35 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'] 05:25:35 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 05:25:36 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:25:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:25:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:37 INFO - (blobuploader) - INFO - Done attempting. 05:25:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:38 INFO - (blobuploader) - INFO - Done attempting. 05:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:41 INFO - (blobuploader) - INFO - Done attempting. 05:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:42 INFO - (blobuploader) - INFO - Done attempting. 05:25:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:42 INFO - Return code: 0 05:25:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:42 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f45f56e6ee2d297da2605c867261c94413725ed0222cb1cfa3d063894bdf722594484d3a1a90e9ff80ba6eb6e11ead2dbb36cfcfce93c4e1cc7ba7e19f84f72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c366235bc73ed7cbbca3adf0d83c863cf88a9d17fdeedf159f5ada79244296838393bb978c27995f53b0cc97d9f34b69708e776de1cd50e03fd4dff92898779b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/225a96f4d4ac242e529b165cd559cc64881a101b8219c6ea06210cd639b11ab798756d0be51caca15e16a85772d1a7b0087fa4ad0a205f03e2ebefdd820da11e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fff655cbb4bb1542b289c339203bcff08b82c9ed10987b5f4d531e98ed8fc9860187019646b8f009d3706301592b2c1dab7c6e41e940e758447678bc4e8fc3b4"} 05:25:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:42 INFO - Contents: 05:25:42 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f45f56e6ee2d297da2605c867261c94413725ed0222cb1cfa3d063894bdf722594484d3a1a90e9ff80ba6eb6e11ead2dbb36cfcfce93c4e1cc7ba7e19f84f72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c366235bc73ed7cbbca3adf0d83c863cf88a9d17fdeedf159f5ada79244296838393bb978c27995f53b0cc97d9f34b69708e776de1cd50e03fd4dff92898779b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/225a96f4d4ac242e529b165cd559cc64881a101b8219c6ea06210cd639b11ab798756d0be51caca15e16a85772d1a7b0087fa4ad0a205f03e2ebefdd820da11e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fff655cbb4bb1542b289c339203bcff08b82c9ed10987b5f4d531e98ed8fc9860187019646b8f009d3706301592b2c1dab7c6e41e940e758447678bc4e8fc3b4"} 05:25:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:42 INFO - Copying logs to upload dir... 05:25:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.021311 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-17 05:25:42.263701) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 05:25:42.266827) ========= 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-1487336686.926345-239098679 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/8f45f56e6ee2d297da2605c867261c94413725ed0222cb1cfa3d063894bdf722594484d3a1a90e9ff80ba6eb6e11ead2dbb36cfcfce93c4e1cc7ba7e19f84f72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c366235bc73ed7cbbca3adf0d83c863cf88a9d17fdeedf159f5ada79244296838393bb978c27995f53b0cc97d9f34b69708e776de1cd50e03fd4dff92898779b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/225a96f4d4ac242e529b165cd559cc64881a101b8219c6ea06210cd639b11ab798756d0be51caca15e16a85772d1a7b0087fa4ad0a205f03e2ebefdd820da11e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fff655cbb4bb1542b289c339203bcff08b82c9ed10987b5f4d531e98ed8fc9860187019646b8f009d3706301592b2c1dab7c6e41e940e758447678bc4e8fc3b4"} build_url:https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/bkheuIwGQzWQeKLtfoJKuA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f45f56e6ee2d297da2605c867261c94413725ed0222cb1cfa3d063894bdf722594484d3a1a90e9ff80ba6eb6e11ead2dbb36cfcfce93c4e1cc7ba7e19f84f72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c366235bc73ed7cbbca3adf0d83c863cf88a9d17fdeedf159f5ada79244296838393bb978c27995f53b0cc97d9f34b69708e776de1cd50e03fd4dff92898779b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/225a96f4d4ac242e529b165cd559cc64881a101b8219c6ea06210cd639b11ab798756d0be51caca15e16a85772d1a7b0087fa4ad0a205f03e2ebefdd820da11e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fff655cbb4bb1542b289c339203bcff08b82c9ed10987b5f4d531e98ed8fc9860187019646b8f009d3706301592b2c1dab7c6e41e940e758447678bc4e8fc3b4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 05:25:42.291590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:25:42.291912) ========= 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-1487336686.926345-239098679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004771 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 05:25:42.375397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 05:25:42.375722) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 05:25:42.376027) ========= ========= Total master_lag: 0.18 =========