builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-073 starttime: 1487268031.12 results: success (0) revision: 4158b1d8bb2ab89048c8be560b35fcfc12726a84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.116046) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.116484) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.116757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.132562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.132854) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 10:00:31-- 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% 219M=0s 2017-02-16 10:00:31 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147694 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.293716) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.294022) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020145 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:31.343218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 10:00:31.343729) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4158b1d8bb2ab89048c8be560b35fcfc12726a84 --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 4158b1d8bb2ab89048c8be560b35fcfc12726a84 --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=0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 10:00:31,900 truncating revision to first 12 chars 2017-02-16 10:00:31,900 Setting DEBUG logging. 2017-02-16 10:00:31,900 attempt 1/10 2017-02-16 10:00:31,900 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4158b1d8bb2a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 10:00:32,827 unpacking tar archive at: mozilla-central-4158b1d8bb2a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.281059 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 10:00:32.637666) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:32.638007) ========= script_repo_revision: 4158b1d8bb2ab89048c8be560b35fcfc12726a84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:32.638411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:32.638682) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 10:00:32.646525) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-16 10:00:32.646928) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:00:33 INFO - MultiFileLogger online at 20170216 10:00:33 in /builds/slave/test 10:00:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 10:00:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:00:33 INFO - {'append_to_log': False, 10:00:33 INFO - 'base_work_dir': '/builds/slave/test', 10:00:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 10:00:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:00:33 INFO - 'branch': 'Firefox-Non-PGO', 10:00:33 INFO - 'buildbot_json_path': 'buildprops.json', 10:00:33 INFO - 'config_files': ('talos/linux_config.py',), 10:00:33 INFO - 'default_actions': ('clobber', 10:00:33 INFO - 'read-buildbot-config', 10:00:33 INFO - 'download-and-extract', 10:00:33 INFO - 'populate-webroot', 10:00:33 INFO - 'create-virtualenv', 10:00:33 INFO - 'install', 10:00:33 INFO - 'run-tests'), 10:00:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:00:33 INFO - 'download_minidump_stackwalk': True, 10:00:33 INFO - 'download_symbols': 'ondemand', 10:00:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:00:33 INFO - 'tooltool.py': '/tools/tooltool.py', 10:00:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:00:33 INFO - '/tools/misc-python/virtualenv.py')}, 10:00:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:00:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:00:33 INFO - 'installer_path': 'installer.exe', 10:00:33 INFO - 'log_level': 'info', 10:00:33 INFO - 'log_name': 'talos', 10:00:33 INFO - 'log_to_console': True, 10:00:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:00:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:00:33 INFO - 'opt_config_files': (), 10:00:33 INFO - 'pip_index': False, 10:00:33 INFO - 'sps_profile': False, 10:00:33 INFO - 'sps_profile_interval': 0, 10:00:33 INFO - 'suite': 'dromaeojs-e10s', 10:00:33 INFO - 'system_bits': '32', 10:00:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:00:33 INFO - 'title': 'talos-linux64-ix-073', 10:00:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:00:33 INFO - 'use_talos_json': True, 10:00:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:00:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:00:33 INFO - 'work_dir': 'build'} 10:00:33 INFO - [mozharness: 2017-02-16 18:00:33.290597Z] Running clobber step. 10:00:33 INFO - Running pre-action listener: _resource_record_pre_action 10:00:33 INFO - Running main action method: clobber 10:00:33 INFO - rmtree: /builds/slave/test/build 10:00:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:00:34 INFO - Running post-action listener: _resource_record_post_action 10:00:34 INFO - [mozharness: 2017-02-16 18:00:34.203992Z] Finished clobber step (success) 10:00:34 INFO - [mozharness: 2017-02-16 18:00:34.204090Z] Running read-buildbot-config step. 10:00:34 INFO - Running pre-action listener: _resource_record_pre_action 10:00:34 INFO - Running main action method: read_buildbot_config 10:00:34 INFO - Using buildbot properties: 10:00:34 INFO - { 10:00:34 INFO - "project": "", 10:00:34 INFO - "product": "firefox", 10:00:34 INFO - "who": "kwierso@gmail.com", 10:00:34 INFO - "installer_path": "public/build/target.tar.bz2", 10:00:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 10:00:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:00:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 10:00:34 INFO - "stage_platform": "linux64", 10:00:34 INFO - "basedir": "/builds/slave/test", 10:00:34 INFO - "buildnumber": 677, 10:00:34 INFO - "platform": "ubuntu64_hw", 10:00:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:00:34 INFO - "slavebuilddir": "test", 10:00:34 INFO - "taskId": "DEMFg2uQTvGqJ1ST_jRHqQ", 10:00:34 INFO - "branch": "mozilla-central", 10:00:34 INFO - "script_repo_revision": "production", 10:00:34 INFO - "revision": "4158b1d8bb2ab89048c8be560b35fcfc12726a84", 10:00:34 INFO - "slavename": "talos-linux64-ix-073", 10:00:34 INFO - "repo_path": "mozilla-central" 10:00:34 INFO - } 10:00:34 INFO - Finding installer, test and symbols from parent task. 10:00:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DEMFg2uQTvGqJ1ST_jRHqQ'}, attempt #1 10:00:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DEMFg2uQTvGqJ1ST_jRHqQ'}, attempt #1 10:00:35 INFO - Task dependencies: d0PYkEtpRLay6gAB9fZa_w 10:00:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w'}, attempt #1 10:00:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2 10:00:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/test_packages.json 10:00:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.crashreporter-symbols.zip 10:00:35 INFO - Running post-action listener: _resource_record_post_action 10:00:35 INFO - [mozharness: 2017-02-16 18:00:35.599739Z] Finished read-buildbot-config step (success) 10:00:35 INFO - [mozharness: 2017-02-16 18:00:35.599954Z] Running download-and-extract step. 10:00:35 INFO - Running pre-action listener: _resource_record_pre_action 10:00:35 INFO - Running main action method: download_and_extract 10:00:35 INFO - mkdir: /builds/slave/test/build/tests 10:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:35 INFO - trying https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.test_packages.json 10:00:35 INFO - Downloading https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:00:36 INFO - Downloaded 896 bytes. 10:00:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:00:36 INFO - Using the following test package requirements: 10:00:36 INFO - {u'common': [u'target.common.tests.zip'], 10:00:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:00:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:00:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:00:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:00:36 INFO - u'mozbase': [u'target.common.tests.zip'], 10:00:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:00:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:00:36 INFO - u'web-platform': [u'target.common.tests.zip', 10:00:36 INFO - u'target.web-platform.tests.zip'], 10:00:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:00:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:00:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.common.tests.zip 10:00:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:00:36 INFO - Fetch https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.common.tests.zip into memory 10:00:37 INFO - Expected file size: 57026149 10:00:37 INFO - Obtained file size: 57026149 10:00:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:00:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.common.tests.zip 10:00:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:00:46 INFO - Fetch https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.common.tests.zip into memory 10:00:48 INFO - Expected file size: 57026149 10:00:48 INFO - Obtained file size: 57026149 10:00:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.talos.tests.zip 10:00:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:00:58 INFO - Fetch https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.talos.tests.zip into memory 10:00:59 INFO - Expected file size: 14403647 10:00:59 INFO - Obtained file size: 14403647 10:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:59 INFO - trying https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2 10:00:59 INFO - Downloading https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:00:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:01:00 INFO - Downloaded 56310532 bytes. 10:01:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2 10:01:00 INFO - mkdir: /builds/slave/test/properties 10:01:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:01:00 INFO - Writing to file /builds/slave/test/properties/build_url 10:01:00 INFO - Contents: 10:01:00 INFO - build_url:https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2 10:01:00 INFO - Running post-action listener: _resource_record_post_action 10:01:00 INFO - Running post-action listener: set_extra_try_arguments 10:01:00 INFO - [mozharness: 2017-02-16 18:01:00.851868Z] Finished download-and-extract step (success) 10:01:00 INFO - [mozharness: 2017-02-16 18:01:00.851965Z] Running populate-webroot step. 10:01:00 INFO - Running pre-action listener: _resource_record_pre_action 10:01:00 INFO - Running main action method: populate_webroot 10:01:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:01:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:01:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:01:00 INFO - '--tppagecycles', 10:01:00 INFO - '7'], 10:01:00 INFO - 'tests': ['tsvgm']}}, 10:01:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:01:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:01:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:01:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:01:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['damp', 'tps']}, 10:01:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'tests': ['damp', 'tps']}, 10:01:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['dromaeo_dom']}, 10:01:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:01:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['basic_compositor_video']}, 10:01:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:01:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other-e10s': {'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tsvgx', 10:01:00 INFO - 'tsvgr_opacity', 10:01:00 INFO - 'tart', 10:01:00 INFO - 'tscrollx', 10:01:00 INFO - 'cart', 10:01:00 INFO - 'tsvg_static']}, 10:01:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:01:00 INFO - 'tsvgr_opacity', 10:01:00 INFO - 'tart', 10:01:00 INFO - 'tscrollx', 10:01:00 INFO - 'cart', 10:01:00 INFO - 'tsvg_static']}, 10:01:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tp5o']}, 10:01:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:01:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s', 10:01:00 INFO - '--xperf_path', 10:01:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:00 INFO - 'tests': ['tp5n']}, 10:01:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:01:00 INFO - 'talos_options': ['--xperf_path', 10:01:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:00 INFO - 'tests': ['tp5n']}}, 10:01:00 INFO - 'talos.zip': {'path': '', 10:01:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:01:00 INFO - Running post-action listener: _resource_record_post_action 10:01:00 INFO - [mozharness: 2017-02-16 18:01:00.865018Z] Finished populate-webroot step (success) 10:01:00 INFO - [mozharness: 2017-02-16 18:01:00.865107Z] Running create-virtualenv step. 10:01:00 INFO - Running pre-action listener: _resource_record_pre_action 10:01:00 INFO - Running main action method: create_virtualenv 10:01:00 INFO - Creating virtualenv /builds/slave/test/build/venv 10:01:00 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 10:01:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:01:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:01:00 INFO - Using real prefix '/usr' 10:01:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:01:01 INFO - Installing distribute.............................................................................................................................................................................................done. 10:01:03 INFO - Installing pip.................done. 10:01:03 INFO - Return code: 0 10:01:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:01:03 INFO - Reading from file tmpfile_stdout 10:01:03 INFO - Output received: 10:01:03 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) 10:01:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:03 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:03 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 10:01:03 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 10:01:03 INFO - Using env: {'DISPLAY': ':0', 10:01:03 INFO - 'HOME': '/home/cltbld', 10:01:03 INFO - 'LANG': 'en_US.UTF-8', 10:01:03 INFO - 'LANGUAGE': 'en_US:en', 10:01:03 INFO - 'LOGNAME': 'cltbld', 10:01:03 INFO - 'MAIL': '/var/mail/cltbld', 10:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:03 INFO - 'MOZ_NO_REMOTE': '1', 10:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:03 INFO - 'NO_EM_RESTART': '1', 10:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:03 INFO - 'PWD': '/builds/slave/test', 10:01:03 INFO - 'SHELL': '/bin/bash', 10:01:03 INFO - 'SHLVL': '1', 10:01:03 INFO - 'TERM': 'linux', 10:01:03 INFO - 'TMOUT': '86400', 10:01:03 INFO - 'USER': 'cltbld', 10:01:03 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:03 INFO - '_': '/tools/buildbot/bin/python'} 10:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:03 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 10:01:03 INFO - Cleaning up... 10:01:03 INFO - Return code: 0 10:01:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:03 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:03 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 10:01:03 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 10:01:03 INFO - Using env: {'DISPLAY': ':0', 10:01:03 INFO - 'HOME': '/home/cltbld', 10:01:03 INFO - 'LANG': 'en_US.UTF-8', 10:01:03 INFO - 'LANGUAGE': 'en_US:en', 10:01:03 INFO - 'LOGNAME': 'cltbld', 10:01:03 INFO - 'MAIL': '/var/mail/cltbld', 10:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:03 INFO - 'MOZ_NO_REMOTE': '1', 10:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:03 INFO - 'NO_EM_RESTART': '1', 10:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:03 INFO - 'PWD': '/builds/slave/test', 10:01:03 INFO - 'SHELL': '/bin/bash', 10:01:03 INFO - 'SHLVL': '1', 10:01:03 INFO - 'TERM': 'linux', 10:01:03 INFO - 'TMOUT': '86400', 10:01:03 INFO - 'USER': 'cltbld', 10:01:03 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:03 INFO - '_': '/tools/buildbot/bin/python'} 10:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:04 INFO - Downloading/unpacking psutil>=3.1.1 10:01:04 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 10:01:04 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 10:01:04 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 10:01:04 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 10:01:04 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 10:01:04 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 10:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:01:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:06 INFO - Installing collected packages: psutil 10:01:06 INFO - Running setup.py install for psutil 10:01:06 INFO - building 'psutil._psutil_linux' extension 10:01:06 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 10:01:06 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 10:01:06 INFO - building 'psutil._psutil_posix' extension 10:01:06 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 10:01:06 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 10:01:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:06 INFO - Successfully installed psutil 10:01:06 INFO - Cleaning up... 10:01:06 INFO - Return code: 0 10:01:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:01:06 INFO - Using env: {'DISPLAY': ':0', 10:01:06 INFO - 'HOME': '/home/cltbld', 10:01:06 INFO - 'LANG': 'en_US.UTF-8', 10:01:06 INFO - 'LANGUAGE': 'en_US:en', 10:01:06 INFO - 'LOGNAME': 'cltbld', 10:01:06 INFO - 'MAIL': '/var/mail/cltbld', 10:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:06 INFO - 'MOZ_NO_REMOTE': '1', 10:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:06 INFO - 'NO_EM_RESTART': '1', 10:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:06 INFO - 'PWD': '/builds/slave/test', 10:01:06 INFO - 'SHELL': '/bin/bash', 10:01:06 INFO - 'SHLVL': '1', 10:01:06 INFO - 'TERM': 'linux', 10:01:06 INFO - 'TMOUT': '86400', 10:01:06 INFO - 'USER': 'cltbld', 10:01:06 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:06 INFO - '_': '/tools/buildbot/bin/python'} 10:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:01:06 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 10:01:06 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 10:01:06 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 10:01:06 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 10:01:06 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 10:01:06 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 10:01:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:01:09 INFO - Installing collected packages: mozsystemmonitor 10:01:09 INFO - Running setup.py install for mozsystemmonitor 10:01:09 INFO - Successfully installed mozsystemmonitor 10:01:09 INFO - Cleaning up... 10:01:09 INFO - Return code: 0 10:01:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:09 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:09 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 10:01:09 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 10:01:09 INFO - Using env: {'DISPLAY': ':0', 10:01:09 INFO - 'HOME': '/home/cltbld', 10:01:09 INFO - 'LANG': 'en_US.UTF-8', 10:01:09 INFO - 'LANGUAGE': 'en_US:en', 10:01:09 INFO - 'LOGNAME': 'cltbld', 10:01:09 INFO - 'MAIL': '/var/mail/cltbld', 10:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:09 INFO - 'MOZ_NO_REMOTE': '1', 10:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:09 INFO - 'NO_EM_RESTART': '1', 10:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:09 INFO - 'PWD': '/builds/slave/test', 10:01:09 INFO - 'SHELL': '/bin/bash', 10:01:09 INFO - 'SHLVL': '1', 10:01:09 INFO - 'TERM': 'linux', 10:01:09 INFO - 'TMOUT': '86400', 10:01:09 INFO - 'USER': 'cltbld', 10:01:09 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:09 INFO - '_': '/tools/buildbot/bin/python'} 10:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:09 INFO - Downloading/unpacking jsonschema==2.5.1 10:01:09 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 10:01:09 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 10:01:09 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 10:01:09 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 10:01:09 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 10:01:09 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 10:01:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:01:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:11 INFO - Downloading functools32-3.2.3-2.tar.gz 10:01:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:01:11 INFO - warning: no files found matching '*.txt' 10:01:11 INFO - no previously-included directories found matching 'build' 10:01:11 INFO - no previously-included directories found matching 'dist' 10:01:11 INFO - no previously-included directories found matching '.git*' 10:01:11 INFO - Installing collected packages: jsonschema, functools32 10:01:11 INFO - Running setup.py install for functools32 10:01:11 INFO - warning: no files found matching '*.txt' 10:01:11 INFO - no previously-included directories found matching 'build' 10:01:11 INFO - no previously-included directories found matching 'dist' 10:01:11 INFO - no previously-included directories found matching '.git*' 10:01:11 INFO - Successfully installed jsonschema functools32 10:01:11 INFO - Cleaning up... 10:01:11 INFO - Return code: 0 10:01:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:11 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:11 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 10:01:11 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 10:01:11 INFO - Using env: {'DISPLAY': ':0', 10:01:11 INFO - 'HOME': '/home/cltbld', 10:01:11 INFO - 'LANG': 'en_US.UTF-8', 10:01:11 INFO - 'LANGUAGE': 'en_US:en', 10:01:11 INFO - 'LOGNAME': 'cltbld', 10:01:11 INFO - 'MAIL': '/var/mail/cltbld', 10:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:11 INFO - 'MOZ_NO_REMOTE': '1', 10:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:11 INFO - 'NO_EM_RESTART': '1', 10:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:11 INFO - 'PWD': '/builds/slave/test', 10:01:11 INFO - 'SHELL': '/bin/bash', 10:01:11 INFO - 'SHLVL': '1', 10:01:11 INFO - 'TERM': 'linux', 10:01:11 INFO - 'TMOUT': '86400', 10:01:11 INFO - 'USER': 'cltbld', 10:01:11 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:11 INFO - '_': '/tools/buildbot/bin/python'} 10:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:01:12 INFO - Cleaning up... 10:01:12 INFO - Return code: 0 10:01:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:12 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:12 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 10:01:12 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 10:01:12 INFO - Using env: {'DISPLAY': ':0', 10:01:12 INFO - 'HOME': '/home/cltbld', 10:01:12 INFO - 'LANG': 'en_US.UTF-8', 10:01:12 INFO - 'LANGUAGE': 'en_US:en', 10:01:12 INFO - 'LOGNAME': 'cltbld', 10:01:12 INFO - 'MAIL': '/var/mail/cltbld', 10:01:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:12 INFO - 'MOZ_NO_REMOTE': '1', 10:01:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:12 INFO - 'NO_EM_RESTART': '1', 10:01:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:12 INFO - 'PWD': '/builds/slave/test', 10:01:12 INFO - 'SHELL': '/bin/bash', 10:01:12 INFO - 'SHLVL': '1', 10:01:12 INFO - 'TERM': 'linux', 10:01:12 INFO - 'TMOUT': '86400', 10:01:12 INFO - 'USER': 'cltbld', 10:01:12 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:12 INFO - '_': '/tools/buildbot/bin/python'} 10:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:12 INFO - Downloading/unpacking blobuploader==1.2.4 10:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:14 INFO - Downloading blobuploader-1.2.4.tar.gz 10:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:01:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:01:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 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 10:01:14 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 10:01:15 INFO - Downloading docopt-0.6.1.tar.gz 10:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:01:15 INFO - Installing collected packages: blobuploader, requests, docopt 10:01:15 INFO - Running setup.py install for blobuploader 10:01:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:01:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:01:15 INFO - Running setup.py install for requests 10:01:15 INFO - Running setup.py install for docopt 10:01:15 INFO - Successfully installed blobuploader requests docopt 10:01:15 INFO - Cleaning up... 10:01:15 INFO - Return code: 0 10:01:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01: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')]} 10:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:15 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:15 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 10:01:15 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 10:01:15 INFO - Using env: {'DISPLAY': ':0', 10:01:15 INFO - 'HOME': '/home/cltbld', 10:01:15 INFO - 'LANG': 'en_US.UTF-8', 10:01:15 INFO - 'LANGUAGE': 'en_US:en', 10:01:15 INFO - 'LOGNAME': 'cltbld', 10:01:15 INFO - 'MAIL': '/var/mail/cltbld', 10:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:15 INFO - 'MOZ_NO_REMOTE': '1', 10:01:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:15 INFO - 'NO_EM_RESTART': '1', 10:01:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:15 INFO - 'PWD': '/builds/slave/test', 10:01:15 INFO - 'SHELL': '/bin/bash', 10:01:15 INFO - 'SHLVL': '1', 10:01:15 INFO - 'TERM': 'linux', 10:01:15 INFO - 'TMOUT': '86400', 10:01:15 INFO - 'USER': 'cltbld', 10:01:15 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:15 INFO - '_': '/tools/buildbot/bin/python'} 10:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:15 INFO - Running setup.py (path:/tmp/pip-5WZufG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:16 INFO - Running setup.py (path:/tmp/pip-wyqVUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:16 INFO - Running setup.py (path:/tmp/pip-vsqPZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:16 INFO - Running setup.py (path:/tmp/pip-rW4Uee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:16 INFO - Running setup.py (path:/tmp/pip-X18b43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:16 INFO - Running setup.py (path:/tmp/pip-KaGtfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:16 INFO - Running setup.py (path:/tmp/pip-3F36sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:16 INFO - Running setup.py (path:/tmp/pip-CJhaVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:16 INFO - Running setup.py (path:/tmp/pip-JvMzVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:16 INFO - Running setup.py (path:/tmp/pip-IKnElq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:16 INFO - Running setup.py (path:/tmp/pip-2ab4iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:16 INFO - Running setup.py (path:/tmp/pip-pAfHxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:16 INFO - Running setup.py (path:/tmp/pip-BhBTUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:16 INFO - Running setup.py (path:/tmp/pip-qvwjAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:17 INFO - Running setup.py (path:/tmp/pip-RWNHtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:17 INFO - Running setup.py (path:/tmp/pip-OyrZ_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:17 INFO - Running setup.py (path:/tmp/pip-0yaLXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:01:17 INFO - Running setup.py install for manifestparser 10:01:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:01:17 INFO - Running setup.py install for mozcrash 10:01:17 INFO - Running setup.py install for mozdebug 10:01:17 INFO - Running setup.py install for mozdevice 10:01:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:01:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:01:17 INFO - Running setup.py install for mozfile 10:01:17 INFO - Running setup.py install for mozhttpd 10:01:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:01:17 INFO - Running setup.py install for mozinfo 10:01:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:01:17 INFO - Running setup.py install for mozInstall 10:01:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Running setup.py install for mozleak 10:01:18 INFO - Running setup.py install for mozlog 10:01:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Running setup.py install for moznetwork 10:01:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Running setup.py install for mozprocess 10:01:18 INFO - Running setup.py install for mozprofile 10:01:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Running setup.py install for mozrunner 10:01:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:01:18 INFO - Running setup.py install for mozscreenshot 10:01:18 INFO - Running setup.py install for moztest 10:01:18 INFO - Running setup.py install for mozversion 10:01:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:01:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:01:19 INFO - Cleaning up... 10:01:19 INFO - Return code: 0 10:01:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01: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')]} 10:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:19 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:19 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 10:01:19 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 10:01:19 INFO - Using env: {'DISPLAY': ':0', 10:01:19 INFO - 'HOME': '/home/cltbld', 10:01:19 INFO - 'LANG': 'en_US.UTF-8', 10:01:19 INFO - 'LANGUAGE': 'en_US:en', 10:01:19 INFO - 'LOGNAME': 'cltbld', 10:01:19 INFO - 'MAIL': '/var/mail/cltbld', 10:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:19 INFO - 'MOZ_NO_REMOTE': '1', 10:01:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:19 INFO - 'NO_EM_RESTART': '1', 10:01:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:19 INFO - 'PWD': '/builds/slave/test', 10:01:19 INFO - 'SHELL': '/bin/bash', 10:01:19 INFO - 'SHLVL': '1', 10:01:19 INFO - 'TERM': 'linux', 10:01:19 INFO - 'TMOUT': '86400', 10:01:19 INFO - 'USER': 'cltbld', 10:01:19 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:19 INFO - '_': '/tools/buildbot/bin/python'} 10:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:19 INFO - Running setup.py (path:/tmp/pip-vPm8PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:19 INFO - Running setup.py (path:/tmp/pip-W6LBKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:19 INFO - Running setup.py (path:/tmp/pip-MwZoyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:19 INFO - Running setup.py (path:/tmp/pip-jx02q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:19 INFO - Running setup.py (path:/tmp/pip-wZsOTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:19 INFO - Running setup.py (path:/tmp/pip-LbPpHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:19 INFO - Running setup.py (path:/tmp/pip-17PizV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:19 INFO - Running setup.py (path:/tmp/pip-mVssXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:19 INFO - Running setup.py (path:/tmp/pip-sSAog8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:19 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)) 10:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:19 INFO - Running setup.py (path:/tmp/pip-pq6OXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:20 INFO - Running setup.py (path:/tmp/pip-oBQO1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:20 INFO - Running setup.py (path:/tmp/pip-vuYkl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:20 INFO - Running setup.py (path:/tmp/pip-wBFHt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:20 INFO - Running setup.py (path:/tmp/pip-2VsevT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:20 INFO - Running setup.py (path:/tmp/pip-zlbNL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:20 INFO - Running setup.py (path:/tmp/pip-wIptv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:20 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)) 10:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:20 INFO - Running setup.py (path:/tmp/pip-AbgCes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:20 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)) 10:01:20 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)) 10:01:20 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)) 10:01:20 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)) 10:01:20 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)) 10:01:20 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)) 10:01:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:01:20 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 10:01:20 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 10:01:20 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 10:01:20 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 10:01:20 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 10:01:20 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 10:01:22 INFO - Downloading blessings-1.6.tar.gz 10:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:01:22 INFO - Installing collected packages: blessings 10:01:22 INFO - Running setup.py install for blessings 10:01:22 INFO - Successfully installed blessings 10:01:22 INFO - Cleaning up... 10:01:22 INFO - Return code: 0 10:01:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:01:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:01:22 INFO - Reading from file tmpfile_stdout 10:01:22 INFO - Current package versions: 10:01:22 INFO - argparse == 1.2.1 10:01:22 INFO - blessings == 1.6 10:01:22 INFO - blobuploader == 1.2.4 10:01:22 INFO - docopt == 0.6.1 10:01:22 INFO - functools32 == 3.2.3-2 10:01:22 INFO - jsonschema == 2.5.1 10:01:22 INFO - manifestparser == 1.1 10:01:22 INFO - mozInstall == 1.12 10:01:22 INFO - mozcrash == 1.0 10:01:22 INFO - mozdebug == 0.1 10:01:22 INFO - mozdevice == 0.49 10:01:22 INFO - mozfile == 1.2 10:01:22 INFO - mozhttpd == 0.7 10:01:22 INFO - mozinfo == 0.9 10:01:22 INFO - mozleak == 0.1 10:01:22 INFO - mozlog == 3.4 10:01:22 INFO - moznetwork == 0.27 10:01:22 INFO - mozprocess == 0.24 10:01:22 INFO - mozprofile == 0.28 10:01:22 INFO - mozrunner == 6.13 10:01:22 INFO - mozscreenshot == 0.1 10:01:22 INFO - mozsystemmonitor == 0.3 10:01:22 INFO - moztest == 0.8 10:01:22 INFO - mozversion == 1.4 10:01:22 INFO - psutil == 3.1.1 10:01:22 INFO - requests == 1.2.3 10:01:22 INFO - wsgiref == 0.1.2 10:01:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01: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')]} 10:01:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:22 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:22 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 10:01:22 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 10:01:22 INFO - Using env: {'DISPLAY': ':0', 10:01:22 INFO - 'HOME': '/home/cltbld', 10:01:22 INFO - 'LANG': 'en_US.UTF-8', 10:01:22 INFO - 'LANGUAGE': 'en_US:en', 10:01:22 INFO - 'LOGNAME': 'cltbld', 10:01:22 INFO - 'MAIL': '/var/mail/cltbld', 10:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:22 INFO - 'MOZ_NO_REMOTE': '1', 10:01:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:22 INFO - 'NO_EM_RESTART': '1', 10:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:23 INFO - 'PWD': '/builds/slave/test', 10:01:23 INFO - 'SHELL': '/bin/bash', 10:01:23 INFO - 'SHLVL': '1', 10:01:23 INFO - 'TERM': 'linux', 10:01:23 INFO - 'TMOUT': '86400', 10:01:23 INFO - 'USER': 'cltbld', 10:01:23 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:23 INFO - '_': '/tools/buildbot/bin/python'} 10:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 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)) 10:01:23 INFO - Cleaning up... 10:01:23 INFO - Return code: 0 10:01:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:23 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 0x245a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2552a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, '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': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', '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 10:01:23 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 10:01:23 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 10:01:23 INFO - Using env: {'DISPLAY': ':0', 10:01:23 INFO - 'HOME': '/home/cltbld', 10:01:23 INFO - 'LANG': 'en_US.UTF-8', 10:01:23 INFO - 'LANGUAGE': 'en_US:en', 10:01:23 INFO - 'LOGNAME': 'cltbld', 10:01:23 INFO - 'MAIL': '/var/mail/cltbld', 10:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:23 INFO - 'MOZ_NO_REMOTE': '1', 10:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:23 INFO - 'NO_EM_RESTART': '1', 10:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:23 INFO - 'PWD': '/builds/slave/test', 10:01:23 INFO - 'SHELL': '/bin/bash', 10:01:23 INFO - 'SHLVL': '1', 10:01:23 INFO - 'TERM': 'linux', 10:01:23 INFO - 'TMOUT': '86400', 10:01:23 INFO - 'USER': 'cltbld', 10:01:23 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:23 INFO - '_': '/tools/buildbot/bin/python'} 10:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:01:23 INFO - Cleaning up... 10:01:23 INFO - Return code: 0 10:01:23 INFO - Running post-action listener: _resource_record_post_action 10:01:23 INFO - Running post-action listener: _start_resource_monitoring 10:01:23 INFO - Starting resource monitoring. 10:01:23 INFO - [mozharness: 2017-02-16 18:01:23.486608Z] Finished create-virtualenv step (success) 10:01:23 INFO - [mozharness: 2017-02-16 18:01:23.487119Z] Running install step. 10:01:23 INFO - Running pre-action listener: _resource_record_pre_action 10:01:23 INFO - Running main action method: install 10:01:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:01:23 INFO - Reading from file tmpfile_stdout 10:01:23 INFO - Detecting whether we're running mozinstall >=1.0... 10:01:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:01:23 INFO - Reading from file tmpfile_stdout 10:01:23 INFO - Output received: 10:01:23 INFO - Usage: mozinstall [options] installer 10:01:23 INFO - Options: 10:01:23 INFO - -h, --help show this help message and exit 10:01:23 INFO - -d DEST, --destination=DEST 10:01:23 INFO - Directory to install application into. [default: 10:01:23 INFO - "/builds/slave/test"] 10:01:23 INFO - --app=APP Application being installed. [default: firefox] 10:01:23 INFO - mkdir: /builds/slave/test/build/application 10:01:23 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'] 10:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:01:40 INFO - Reading from file tmpfile_stdout 10:01:40 INFO - Output received: 10:01:40 INFO - /builds/slave/test/build/application/firefox/firefox 10:01:40 INFO - Running post-action listener: _resource_record_post_action 10:01:40 INFO - [mozharness: 2017-02-16 18:01:40.077723Z] Finished install step (success) 10:01:40 INFO - [mozharness: 2017-02-16 18:01:40.078034Z] Running run-tests step. 10:01:40 INFO - Running pre-action listener: _resource_record_pre_action 10:01:40 INFO - Running main action method: run_tests 10:01:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:01:40 INFO - Python 2.7.3 10:01:40 INFO - Return code: 0 10:01:40 INFO - grabbing minidump binary from tooltool 10:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:40 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 0x26f5290>, '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 0x26f5b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26f6040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:01:40 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 10:01:40 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 10:01:40 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 10:01:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:01:40 INFO - Return code: 0 10:01:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:01:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:01:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:01:40 INFO - ENV: RUST_BACKTRACE is now 1 10:01:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:01:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:01:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-073 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:01:40 INFO - Using env: {'DISPLAY': ':0', 10:01:40 INFO - 'HOME': '/home/cltbld', 10:01:40 INFO - 'LANG': 'en_US.UTF-8', 10:01:40 INFO - 'LANGUAGE': 'en_US:en', 10:01:40 INFO - 'LOGNAME': 'cltbld', 10:01:40 INFO - 'MAIL': '/var/mail/cltbld', 10:01:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:01:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:40 INFO - 'MOZ_NO_REMOTE': '1', 10:01:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:40 INFO - 'NO_EM_RESTART': '1', 10:01:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:40 INFO - 'PWD': '/builds/slave/test', 10:01:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:01:40 INFO - 'RUST_BACKTRACE': '1', 10:01:40 INFO - 'SHELL': '/bin/bash', 10:01:40 INFO - 'SHLVL': '1', 10:01:40 INFO - 'TERM': 'linux', 10:01:40 INFO - 'TMOUT': '86400', 10:01:40 INFO - 'USER': 'cltbld', 10:01:40 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166', 10:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:40 INFO - '_': '/tools/buildbot/bin/python'} 10:01:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:01:40 INFO - mozversion application_buildid: 20170216172630 10:01:40 INFO - mozversion application_changeset: 4158b1d8bb2ab89048c8be560b35fcfc12726a84 10:01:40 INFO - mozversion application_display_name: Nightly 10:01:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:01:40 INFO - mozversion application_name: Firefox 10:01:40 INFO - mozversion application_remotingname: firefox 10:01:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:01:40 INFO - mozversion application_vendor: Mozilla 10:01:40 INFO - mozversion application_version: 54.0a1 10:01:40 INFO - mozversion platform_buildid: 20170216172630 10:01:40 INFO - mozversion platform_changeset: 4158b1d8bb2ab89048c8be560b35fcfc12726a84 10:01:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:01:40 INFO - mozversion platform_version: 54.0a1 10:01:40 INFO - using testdate: 1487268100 10:01:40 INFO - actual date: 1487268100 10:01:40 INFO - starting webserver on 'localhost:55951' 10:01:40 INFO - SUITE-START | Running 2 tests 10:01:40 INFO - TEST-START | dromaeo_css 10:01:40 INFO - operating with platform_type : linux_ 10:01:40 INFO - Initialising browser for dromaeo_css test... 10:01:40 INFO - TEST-INFO | started process 12677 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSDFgTC/profile http://localhost:55951/getInfo.html) 10:01:43 INFO - PROCESS | 12677 | Iterating over profile /tmp/tmpSDFgTC/profile 10:01:44 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:44 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/extensions = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:44 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:44 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/localstore.rdf = Thu Feb 16 2017 10:00:59 GMT-0800 (PST) 10:01:44 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:44 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/places.sqlite-shm = Thu Feb 16 2017 10:01:42 GMT-0800 (PST) 10:01:44 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:44 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/pluginreg.dat = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/extensions.ini = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/prefs.js = Thu Feb 16 2017 10:00:59 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/content-prefs.sqlite = Thu Feb 16 2017 10:01:42 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/thumbnails = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/cache2 = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/places.sqlite-wal = Thu Feb 16 2017 10:01:45 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | __metrics Screen width/height:1600/1200 10:01:45 INFO - PROCESS | 12677 | colorDepth:24 10:01:45 INFO - PROCESS | 12677 | Browser inner width/height: 1024/697 10:01:45 INFO - PROCESS | 12677 | __metrics 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/startupCache = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/compatibility.ini = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/permissions.sqlite = Thu Feb 16 2017 10:01:41 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/frequencyCap.json = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/user.js = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | Stat failure 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/minidumps = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/sessionCheckpoints.json = Thu Feb 16 2017 10:01:44 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/secmod.db = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/safebrowsing = Thu Feb 16 2017 10:01:45 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/addons.json = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/blocklist.xml = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/extensions.json = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/key3.db = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/crashes = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/directoryLinks.json = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/cert8.db = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/gmp = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/bookmarkbackups = Thu Feb 16 2017 10:01:43 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/.parentlock = Thu Feb 16 2017 10:01:40 GMT-0800 (PST) 10:01:45 INFO - PROCESS | 12677 | No birth date. Using mtime. 10:01:45 INFO - PROCESS | 12677 | Using date: /tmp/tmpSDFgTC/profile/places.sqlite = Thu Feb 16 2017 10:01:44 GMT-0800 (PST) 10:01:46 ERROR - PROCESS | 12677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 10:01:47 INFO - TEST-INFO | 12677: exit 0 10:01:47 INFO - Browser initialized. 10:01:47 INFO - Running cycle 1/1 for dromaeo_css test... 10:01:47 INFO - TEST-INFO | started process 12797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSDFgTC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:01:59 INFO - PROCESS | 12797 | RSS: Main: 160407552 10:01:59 INFO - PROCESS | 12797 | 10:05:03 INFO - PROCESS | 12797 | Cycle 1(1): loaded http://localhost:55951/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55951/tests/dromaeo/cssquery-ext.html) 10:05:05 INFO - PROCESS | 12797 | RSS: Main: 172347392 10:05:05 INFO - PROCESS | 12797 | 10:06:47 INFO - PROCESS | 12797 | ************************* 10:06:47 INFO - PROCESS | 12797 | A coding exception was thrown and uncaught in a Task. 10:06:47 INFO - PROCESS | 12797 | 10:06:47 ERROR - PROCESS | 12797 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:06:47 INFO - PROCESS | 12797 | Full stack: 10:06:47 INFO - PROCESS | 12797 | ************************* 10:08:07 INFO - PROCESS | 12797 | Cycle 1(1): loaded http://localhost:55951/tests/dromaeo/cssquery-ext.html (next: http://localhost:55951/tests/dromaeo/cssquery-jquery.html) 10:08:08 INFO - PROCESS | 12797 | RSS: Main: 165822464 10:08:08 INFO - PROCESS | 12797 | 10:11:10 INFO - PROCESS | 12797 | Cycle 1(1): loaded http://localhost:55951/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55951/tests/dromaeo/cssquery-mootools.html) 10:11:11 INFO - PROCESS | 12797 | RSS: Main: 172777472 10:11:11 INFO - PROCESS | 12797 | 10:14:13 INFO - PROCESS | 12797 | Cycle 1(1): loaded http://localhost:55951/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55951/tests/dromaeo/cssquery-prototype.html) 10:14:13 INFO - PROCESS | 12797 | RSS: Main: 166129664 10:14:13 INFO - PROCESS | 12797 | 10:17:16 INFO - PROCESS | 12797 | Cycle 1(1): loaded http://localhost:55951/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55951/tests/dromaeo/cssquery-yui.html) 10:17:17 INFO - PROCESS | 12797 | RSS: Main: 169955328 10:17:17 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | Cycle 1(1): loaded http://localhost:55951/tests/dromaeo/cssquery-yui.html (next: http://localhost:55951/tests/dromaeo/cssquery-dojo.html) 10:20:20 INFO - PROCESS | 12797 | RSS: Main: 171364352 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | __start_tp_report 10:20:20 INFO - PROCESS | 12797 | _x_x_mozilla_page_load 10:20:20 INFO - PROCESS | 12797 | _x_x_mozilla_page_load_details 10:20:20 INFO - PROCESS | 12797 | |i|pagename|runs| 10:20:20 INFO - PROCESS | 12797 | |0;dojo.html;2608.1393140263713;2620.921372358829;2624.4882247961655;2649.5495765719834;2661.1218297961673;4655.720656760596;4737.336772851803;4788.760561971896;4795.68828026178;4798.448178459478;4715.03341814928;4721.504242054586;4742.7865746041425;4753.47711751707;4809.831655892044;4153.937690934638;4335.848245311415;4383.561643835623;4446.666500012496;4518.299663552139;4425.933610995821;4431.113777244548;4472.53038430965;4482.9551704482865;4527.796249168795;4237.915241695179;4269.743815371087;4335.414719012924;4369.541198174178;4404.229259879508;4396.1427521633295;4396.582324679151;4412.911741765147;4442.489113751928;4444.377787109807;4343.348497725335;4379.8467053653285;4386.978065109654;4416.80124394403;4467.419235499397;4560.8859778505475;4589.196890544142;4645.256758918591;4720.457147428021;4774.069056533977;5117.155669314596;5118.360204974379;5122.8463146105305;5166.405863325728;5176.5858731301405;5156.793728250866;5157.174852023658;5158.148905430637;5186.818461353872;5228.215767634847;4689.577937985598;4692.202325604655;4731.266653668687;4746.240601503743;4820.0841840049925;5005.224190250517;5174.146265866165;5192.039472697487;5208.6874787512115;5252.474752524717;4861.611071114302;4898.510148985073;4903.043906438282;4905.386826646669;4922.753862306942;4969.676970996874;5028.899422011539;5050.116229659795;5090.465501122403;5128.487151284841;5026.648134630541;5065.189569668835;5080.949190508121;5096.337476128079;5132.8460146195675;4910.508949105061;4927.457979622239;4949.257611358325;4972.776225069881;5070.416902056237;4288.185244803477;4296.720713153635;4315.28797748368;4317.56824317572;4341.696081274344;3706.5181526401393;3734.79458629778;3749.8687545935763;3778.131029863147;3792.6586607205486;4362.9965108025335;4374.671899607542;4414.6247568957;4440;4440.444944381952;5015.27278544609;5039.924401133986;5063.8987220255385;5093.796248150033;5104.2343648453025;4400.933985990213;4407.228734971432;4407.2507673695545;4409.801558929856;4442.755648439367;4506.414166158378;4548.113117942035;4549.863504094882;4565.703229290086;4585.472670642852;4752.786124624469;4764.33299338086;4765.8570242892765;4783.115123702126;4810.8797280068275;5087.491250874883;5091.567216786615;5091.923621145612;5093.108705976365;5130.281760553451;4676.438827340741;4771.642126840431;4775.95224047769;4797.808087676453;4824.324594556835;4922.753862306942;5002.224655178453;5010.223415370623;5031.421386540448;5098.515641014098;5117.667351622133;5129.435762066244;5133.127368347465;5137.5889928806355;5140.306058682179;4767.356406885026;4820.68665536739;4828.8792780180775;4847.5394837490385;5017.523335283142;4827.372441582572;4844.0554091953145;4853.053654537332;4859.659823812299;4860.562549370574;4777.378940737682;4783.928241076317;4800.303955926441;4808;4815.927761083516;4849.684770489906;4878.512148785093;4881.658283920234;4899.240617704261;4933.383327084114;4816;4823.324734537097;4823.903521929612;4831.95168048315;4838.153323168361 10:20:20 INFO - PROCESS | 12797 | |1;ext.html;512.7051945131548;516.150931717325;529.9390570084441;539.8677324055602;544.1674238415225;38255.617443825555;39296.41055384168;39420.211595768065;39514.209715805664;42995.14009719811;38297.425538616946;38515.61484385155;38939.610603893954;39030.609693903054;39105;7596.3922886168975;7774.80562985927;7775.30022297993;9191.35660503765;9534.094261045224;9252.537373131316;9330.626774928995;9347.065293470667;9354.672586459475;9356.672516461926;9311.255099592016;9319.394239374453;9340.532973351339;9347.485888276142;9349.345545811795;9269.351145419823;9285.535723213847;9307.301952353533;9324.440533567973;9349.76625584359;9103.36276460648;9125.817483650357;9145.634174633007;9148.405353651991;9163.08369163085;32796.18009549756;33057.33885322304;33129.17177070568;33306.83346583276;33337.3332533348;3408.642092580268;3519.225770330523;3543.539339885824;3559.928801423957;3588.3540962626716;25334.366640834127;25369.11208107726;25400.745992540207;25522.23433297004;25666.87166564174;3401.829908504565;3447.793132412063;3473.322702073097;3611.422172452395;3630.709543236535;225055;225632;225792;226262;226610;38578;39471.40792888109;45276.54723452789;45793.5420645796;46194.53805461902;45940.77029614831;46250;46274.30588541175;46347.53652463432;46410.303845442344;11689.473973671205;11707.882921170849;11712.238704484182;11719.707007324885;11826.645200643994;9014.819703606023;9167.44995300284;9174.53207486799;9174.908250917406;9177.357584968988;15784.763228551581;15797.44557474559;15842.28709708066;15842.76235856463;15884.60288492797;6487.767324208386;6529.477641788645;6599.340065993362;6607.702653380609;6625;774.825664225556;787.7597332813547;791.877259024852;795.5902710104301;816.3061397811813;25935.22194334173;27109;27165.728342716717;27404.588931166047;27651.86174069117;948.5257371314343;950.092661508258;953.1659797677032;954.3891909178132;1095.775366049958;14297.92851035759;14472.710545789025;14485.348159332856;14575.41698332076;14668.339924703414;318993;321886;322696;323286;324613;49047.50952490501;49217;49335.59966005056;49336.75331623391;49429.50570494321;1145.495981768036;1153.3541216918552;1176.2883455509464;1177.1700950829472;1181.1849823621674;24620;25576.744232557437;26757.464850702487;26853.462930741665;26930;41144.38283425691;41214.38178427266;41438.79280603577;41490.58509414867;41598.792006039774;271436;273607;273933;274087;275971;24183.15358962428;24975.125870594442;24977.12580059762;24990.126135493923;25064.74935250697;39950.800245999744;40501.1899762009;40848.1830363397;41264.1747165061;41378;33915.830420847735;34300.31399372048;34417;34632.30735385228;34738.47892281567;33010;33320.833395832866;33391;33872.66127338794;34183.82908085443;30068.849655751583;34066;34281.828590857884;34362.656373435944;34493;23719.644205337278;24258.878705607065;26938.32654183661;27747.58378624282;28803.423931520832;22547.661785073564;22557.774422256225;22616.773832262126;22619.43451413662;23038.884805576534 10:20:20 INFO - PROCESS | 12797 | |2;jquery.html;382.38627003659144;386.0329873666465;395.89112993926665;400.135706873154;412.1859327827541;12594.685132871682;15911.920440397811;16566.751498727528;18183;18220.362287319946;17027.404040858542;18177;18486.722699159523;18560.628787424244;18792;5198.714070726099;5358.089124848766;5403.135498320269;5410.404855465896;5596.3284405871145;5644.915326270088;5672.801451949183;5697.6011679182475;5716.085426331788;5726.198332233491;5527.364353099385;5683.687397193174;5691.430856914317;5703.74333155009;5752.74112664929;5660.066089095315;5667.801626943037;5715.228444160027;5741.712914354265;5749.453801888814;5388.649737767072;5528.5024347808485;5557.527610153142;5577.8047768328315;5590.91613625796;12849.614511564669;12876.163049401759;12983.675408114777;13002;13016.869831301661;2155.353393981815;2184.137265780008;2185.267935241696;2221.4557433428754;2276.0440614941763;9563.09150630689;9984.700458986243;14267.500637477742;14373.209473478955;15274.694506109927;1753.4301352060631;1780.2789870102629;1830.1056863642686;1849.4636555398918;1862.4878158506383;81007.5949620254;84987.15012849915;88462;89330.55334723285;89508;17584.912075439795;17827.910860445616;17949.820501795075;17987.640247194984;18061.90969045173;12302.138850280395;33147.66852331494;33188.34928157901;33322.83338583291;33324.66675333264;6657.833554161185;6708.899366509506;6760.695768690322;7015.403690686247;7049.977753225856;6388.552801303864;6393.1688880445245;6584.835379115465;6590.242122155918;6614.173228346457;3967.107400834789;3979.422983667352;4009.67922566194;4137.338025915839;4145.398917156996;1974.7334109895266;1997.8401727861735;2003.6994450832492;2031.9085641146185;2040.7857174996411;897.228383590112;922.460360688991;923.4782347973508;926.7080869526078;932.1470854168496;16249.431269905497;16482;17064.402745903833;17463.301467941394;17501.29994800194;869.9782505437415;880.9251213646912;886.4548302793736;889.4929889962659;914.2274777812737;8829.60266787996;9520.476373799369;9528;9557.856632150524;9641.084097010913;453093;456998;462138;464944;465955.36178553157;82291.17708822955;83891.58054209812;84211;84396;100492.49753751184;944.5135755086136;967.888692800337;989.9852651032749;995.1491474789096;1000.849872519128;15050.973308250112;15507.457238996582;16503.66992660164;16769.245383957994;16837.579060523585;31647.84176079105;33113.834430827694;37241.81379093087;39624.40563391609;39944.80027599844;51850;53532.197017043996;54548.72725636346;55575.72212138913;56159;6750.5274630775375;7808.84382312362;7919.703286046982;8034.196580341919;8121.512709237465;9485.905140948502;11686.88313116892;12118.515259389525;12207.206531575419;12214.694632634255;9827.213822894008;10050.748731281485;10168.288219824837;10280.588776448858;10287.948560257151;9784.951075244579;9908.653197138066;10193.592256309665;10205.38767673942;10614.363138211424;9898.624330160854;9976.451295178835;10338.276320657482;10399.792004159723;10460;7091.787246382617;7093.538919970186;7117;7210.387117095106;7418.18399976013;9046.68336608242;9603.759906002406;9799.461029643438;9924.255680823822;10338.896611033793 10:20:20 INFO - PROCESS | 12797 | |3;mootools.html;1344.435337158394;1351.641814919046;1351.9121257118293;1352.8038434427008;1357.884579810716;7210.315020073092;7290.927090729091;7421.294976977192;7674.07911050674;7701.229877012303;7460.552366857993;7614.543127412359;7621.428392870529;7627.0847498300145;7637;1947.7662680478327;1990.044778506318;1997.590493948737;1997.850161237913;2001.5196352875278;1968.7046942958527;2001.5996800639857;2032.2886989553685;2034.6235946349973;2041.5916816636732;1976.8517361197896;1988.1152886965306;1990.4227773945538;1999.2802591067277;2035.3181684135832;1953.0722906619385;1963.8232559069752;1966.1054220329715;1967.2819420911335;1974.2103158736477;1935.6225536020481;1990.1143990924045;1996.371143089922;2005.6490114230098;2063.298478517296;7626.8474630507635;7643.350315223212;7685.615719214017;7698.576578288192;7747.225277472207;4459.35339375792;4599.149157405891;4606.677532572721;4609.9308510372375;4624.722516649012;2670.6661667291587;2788.5956536302324;2806.3685670724126;2832.107886015898;2836.163331817119;1072.3887384191014;1088.1131877519797;1110.3337997201616;1127.1039523578775;1128.7686024364984;8989.101089890959;9214.861777073349;9919.603215871357;9953.601855925754;9987.550560224807;3903.316919539069;4019.638232559084;4021.4369988201674;4058;4058.0463591056077;8173.836523269621;8188.672453101928;8225.848381226633;8228.917710822934;8247.711330103417;4559.726416415026;4580.65645076614;4593.655475839326;4600.608948239437;4612.7924243409125;4176.937345939813;4180.623743863006;4182.393552934807;4224.429701990253;4288.764117973541;3213.084270982759;3256.5766450361302;3280.114369120324;3290.1445624137896;3318.419276626629;1010.5907107621425;1024.8718910136233;1038.8285932821152;1041.3231399590175;1041.8541404203418;612.4396177497629;629.1946308724839;633.7591715148214;640.9840402961265;645.2321737132797;4726.432828060655;4741.051789642085;4811.615070794328;4924.138275801721;5126.897462050738;534.2120372450682;537.484015345273;538.9514943655012;543.0957455836068;552.9364123125812;3153.7857924698696;3201;3219.951700724491;3286.8520916558814;3378.5607870976614;8466;8667.566621669017;8798.164174403422;9007.324450666096;9017.684381046764;8799.956000219958;8912.465252084896;8949.955250223707;8958;8964.14840590143;6872.725090996404;7087.2558381370245;7096.680649370791;7099.82250443743;7167.354938055496;4869.6591238612955;4873.780679869344;4961;4972.8508144755715;5130.461301563431;7659.348955338635;7962.084360298408;8175.754727358189;8250.174982501701;8258.009038915368;8150.796230094058;8301.211384918422;8358.498490090615;8380.16198380157;8443.451175673561;7035.718571257297;7131.286871312827;7136.607486588287;7139.8572028560175;7149.14210294768;7023.33278338557;7070.222275549789;7118.466115041289;7122.465815063994;7146.678399471933;6463.450606698483;6463.64449955257;6515.641639709672;6543.967280163569;6557.311482294481;6261.655608941552;6276.1213430118905;6287.528435367274;6318.905216421849;6322.178116844781;5300.70846103468;5369.892602148013;5386.111291636836;5437.4018857926385;5441.374241962225;4150.27370210218;4224.91550169001;4311;4335.157558037884;4422.955770442383;4360.890977725456;4361.716488428242;4382.23310920594;4457.0194557197365;4523.660725445539 10:20:20 INFO - PROCESS | 12797 | |4;prototype.html;365.8408592262366;369.5473045519239;370.5775416025729;374.5093926955688;377.15140932900954;4847.830325938593;4897.485763994779;4949.52979466951;4961.933184365361;5004.749762511878;4876.292937524066;4944.145945270524;4950.900981980359;4958.950410495894;4981;3755.155090104718;3853.65317121459;3891.8443262269475;3891.995622254541;3910.335243008695;3865.033741564609;3867.4972253606993;3881.8641347552843;3903.355946268862;3914.1388894443176;3877.7091718121114;3878.3794592865147;3878.5733569307495;3880.301545721769;3881.602623055712;3774.5470543534675;3805.6384643458828;3900.3369427197445;3907.6483116519507;3945.7829819360068;3384.881529146468;3391.5082313064468;3396.303757729684;3398.745094117951;3408.812515311657;4820.373351464323;4869.5130486951375;4870;4894.5105489451125;4901.289313049588;4428.512863585003;4431;4439.156560253574;4439.800208990603;4441.400410944546;4883.169861123618;5169.966006798618;5216.9739151304;5234.607404444682;5237.973810130963;3707.9814600927093;3711.2948539777626;3712.7215458840424;3716.646918542734;3724.255148970217;5188.247704082887;5297.212493067436;5442.57805276652;5474.178873169017;5479.205515200274;3632.4006538921312;3669.3945498992903;3674.283514714605;3675.9632403676155;3706;3761.153740408441;3770.830312635938;3777.3011992780876;3786.1670432504807;3790.8483660653815;4342.956570434319;4343.261645520269;4391.253486907233;4393;4400.383946247528;5172.482751724798;5253.290805741252;5267.157254839208;5276.577873770089;5292.841214763564;1287.575100216926;1298.6298904812083;1298.6753311672082;1298.8310520531597;1298.8311519502404;821.7904434369198;883.4522595990527;892.7857314244535;892.9374943754004;903.214203642835;4401.581849724271;4420.624246938982;4443.844465443694;4444.2222611042935;4456.128426061062;5170.353705786777;5179.720922794034;5192.429747275123;5242.9814521409535;5256.316678831728;4292.800750566404;4503.343027613723;4517.480489743656;4521.977390113028;4525.932111018337;2735.9042433514733;2753.6695596528543;2759.448110377892;2791.4975304445393;2793.469240844233;4879.04838064777;4881.803958030234;4888.9955118401285;4891.535304146171;4903.190973489405;4994.676504815891;5291.708956007379;5295.788168473295;5299.364076310791;5332;4880.804767809319;4904.8773780655765;4921.548143297736;4922.4585295616735;4930.975345123252;5229.555487783581;5235.712035838065;5239.345081864767;5271.710055946806;5273.6572122811895;4913.434954980223;5148.536631703165;5151.5363617274625;5158.793648254028;5199.220116982483;4833.3958255218095;4918.6065114791445;4930.285108659153;4935.407751069751;4937.975310123426;3722.1997270586953;3779.5842457330223;3804.6575808177395;3806.9238615228096;3827.88991192551;5323.414424413234;5331.920021199763;5344.572434205176;5348.893022139613;5380.408155102858;4866.610671146371;5158.509941555546;5196.674847913758;5223.7910483580235;5225.869353266199;5119.74401279942;5152.819651312186;5169.53474187325;5173.611979101658;5196.68819870809;5051.772670229775;5065.56942659878;5103.285540024473;5107.233914912644;5107.897842043212;4986.451490336132;5184.766685499086;5188.377394712658;5189.4032186299055;5211.2704221409795;5084.186530155157;5084.339035925454;5086.872828179326;5098.46466121052;5099.439061703136 10:20:20 INFO - PROCESS | 12797 | |5;yui.html;634.6160572853421;642.4924309795259;704.4751660013287;736.1129838544555;759.0739298056371;12790.808137877928;13412.59762207133;13575.321233938314;13576.728465430686;13618.795718064224;11702.356370399624;13339.333033348343;13414.19514829111;13469.595912122628;13481.123726957765;803.140639515717;811.7240138352959;815.0504662068682;816.1552792859392;816.6447595296036;803.8352137811764;818.5743413425015;829.1459796409707;838.4591938199887;839.1273076000952;870.3602851903846;873.0178549132227;873.3493547307265;874.510274246424;875.8554838451648;863.6459051788736;865.0614083719172;868.5049521772593;870.6778491957984;871.8169184471269;633.37612451735;634.0552576660742;638.7125793392945;639.2456900856987;640.0718957511626;12581.370931453483;12846.164999275017;12881.67795805103;13007.154534955293;14469.565913022627;13043.86956130436;13184;13247.271400072992;13354;13593.456261749518;10226;10281.434521101337;10467.476626168736;10482.213833962483;10543.472826358651;3191.212920764356;3191.6170059592996;3248.886288979898;3249.415105281072;3376.983115084409;59638.40361596372;60001.499962501286;60875.999999999556;61119.08321375183;61904.69047654733;10060.849087263698;10139.847902281472;10162.136218421518;10281;10792.190585706103;19318.227270909007;19345.90327048356;19360.70958935617;19377.224911003403;19409.4177174685;6154.5076393888385;6359.2368915729485;6392.3927226913365;6562.934370656328;6565.901511477332;3287.5561799157285;3361.277325375056;3407.522946787452;3408.7102596279096;3473.235888104613;2893.8842446302333;2919.547470142089;2960.304328482805;2962.6444826620514;2992.820430774117;480.4522843957891;481.4222932481037;482.80929033031754;491.40785353648766;494.2043310270462;656.8062421585644;663.290279401046;683.8187880211748;694.0664805836142;700.1143553404946;8808.251298639687;8817.867731984026;9068.591913363915;9100.681476148304;9472.715818525456;468.79607370793815;486;490.2376804069706;492.48535280631825;505.2345696270154;4174.102567947845;4275.636570891447;4391.560843915583;4426.911461770746;4492.348609451611;62563;62808.05787913185;63160.36839631637;64684.67657661682;66171.33828661748;42795.786021069696;49814.252786208235;53304.46695532995;54742.17886731702;55086;42549;51503;52615;53672.463275367525;53699;8391.706290279812;8575.356848236532;8577.871331930151;8717.171868672465;8775.385722999328;34790;35059.64940350564;35374;35425.82287088548;36580.81709591435;42046;43787.78106109449;43975;44553.33170002518;46770.53229467662;9825.312228144247;10057.798844022933;10119.139873110867;10252.43611601369;10363.585456581652;11332.197649033362;11463;11498.885011149781;11517.25137866036;11546.711332216762;10144;10220.642277520252;10316.638917637847;10400.115990140923;10496.160307175254;10092.243081769044;10155.339902906288;10242.795144096926;10354.896451035393;10673.733156671145;9999;10212.591496340063;10343.94828025855;10740.892591073989;10745.731356716145;8151.30713889326;9027.638894444148;9095.226905713089;9237.214836738953;9576.521173941135;9337.859932100888;9983.800323993624;10685.358878467317;10943.796182420087;10995.615153469591 10:20:20 INFO - PROCESS | 12797 | __end_tp_report 10:20:20 INFO - PROCESS | 12797 | __start_cc_report 10:20:20 INFO - PROCESS | 12797 | _x_x_mozilla_cycle_collect,385 10:20:20 INFO - PROCESS | 12797 | __end_cc_report 10:20:20 INFO - PROCESS | 12797 | __startTimestamp1487269220308__endTimestamp 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | ------- Summary: start ------- 10:20:20 INFO - PROCESS | 12797 | Number of tests: 6 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | [#0] dojo.html Cycles:165 Average:4681.64 Median:4797.81 stddev:486.69 (10.1%) stddev-sans-first:460.20 10:20:20 INFO - PROCESS | 12797 | Values: 2608.1 2620.9 2624.5 2649.5 2661.1 4655.7 4737.3 4788.8 4795.7 4798.4 4715.0 4721.5 4742.8 4753.5 4809.8 4153.9 4335.8 4383.6 4446.7 4518.3 4425.9 4431.1 4472.5 4483.0 4527.8 4237.9 4269.7 4335.4 4369.5 4404.2 4396.1 4396.6 4412.9 4442.5 4444.4 4343.3 4379.8 4387.0 4416.8 4467.4 4560.9 4589.2 4645.3 4720.5 4774.1 5117.2 5118.4 5122.8 5166.4 5176.6 5156.8 5157.2 5158.1 5186.8 5228.2 4689.6 4692.2 4731.3 4746.2 4820.1 5005.2 5174.1 5192.0 5208.7 5252.5 4861.6 4898.5 4903.0 4905.4 4922.8 4969.7 5028.9 5050.1 5090.5 5128.5 5026.6 5065.2 5080.9 5096.3 5132.8 4910.5 4927.5 4949.3 4972.8 5070.4 4288.2 4296.7 4315.3 4317.6 4341.7 3706.5 3734.8 3749.9 3778.1 3792.7 4363.0 4374.7 4414.6 4440.0 4440.4 5015.3 5039.9 5063.9 5093.8 5104.2 4400.9 4407.2 4407.3 4409.8 4442.8 4506.4 4548.1 4549.9 4565.7 4585.5 4752.8 4764.3 4765.9 4783.1 4810.9 5087.5 5091.6 5091.9 5093.1 5130.3 4676.4 4771.6 4776.0 4797.8 4824.3 4922.8 5002.2 5010.2 5031.4 5098.5 5117.7 5129.4 5133.1 5137.6 5140.3 4767.4 4820.7 4828.9 4847.5 5017.5 4827.4 4844.1 4853.1 4859.7 4860.6 4777.4 4783.9 4800.3 4808.0 4815.9 4849.7 4878.5 4881.7 4899.2 4933.4 4816.0 4823.3 4823.9 4832.0 4838.2 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | [#1] ext.html Cycles:180 Average:42282.10 Median:3531.38 stddev:72466.97 (2052.1%) stddev-sans-first:72602.40 10:20:20 INFO - PROCESS | 12797 | Values: 512.7 516.2 529.9 539.9 544.2 38255.6 39296.4 39420.2 39514.2 42995.1 38297.4 38515.6 38939.6 39030.6 39105.0 7596.4 7774.8 7775.3 9191.4 9534.1 9252.5 9330.6 9347.1 9354.7 9356.7 9311.3 9319.4 9340.5 9347.5 9349.3 9269.4 9285.5 9307.3 9324.4 9349.8 9103.4 9125.8 9145.6 9148.4 9163.1 32796.2 33057.3 33129.2 33306.8 33337.3 3408.6 3519.2 3543.5 3559.9 3588.4 25334.4 25369.1 25400.7 25522.2 25666.9 3401.8 3447.8 3473.3 3611.4 3630.7 225055.0 225632.0 225792.0 226262.0 226610.0 38578.0 39471.4 45276.5 45793.5 46194.5 45940.8 46250.0 46274.3 46347.5 46410.3 11689.5 11707.9 11712.2 11719.7 11826.6 9014.8 9167.4 9174.5 9174.9 9177.4 15784.8 15797.4 15842.3 15842.8 15884.6 6487.8 6529.5 6599.3 6607.7 6625.0 774.8 787.8 791.9 795.6 816.3 25935.2 27109.0 27165.7 27404.6 27651.9 948.5 950.1 953.2 954.4 1095.8 14297.9 14472.7 14485.3 14575.4 14668.3 318993.0 321886.0 322696.0 323286.0 324613.0 49047.5 49217.0 49335.6 49336.8 49429.5 1145.5 1153.4 1176.3 1177.2 1181.2 24620.0 25576.7 26757.5 26853.5 26930.0 41144.4 41214.4 41438.8 41490.6 41598.8 271436.0 273607.0 273933.0 274087.0 275971.0 24183.2 24975.1 24977.1 24990.1 25064.7 39950.8 40501.2 40848.2 41264.2 41378.0 33915.8 34300.3 34417.0 34632.3 34738.5 33010.0 33320.8 33391.0 33872.7 34183.8 30068.8 34066.0 34281.8 34362.7 34493.0 23719.6 24258.9 26938.3 27747.6 28803.4 22547.7 22557.8 22616.8 22619.4 23038.9 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | [#2] jquery.html Cycles:180 Average:27662.64 Median:28524.36 stddev:76190.26 (267.1%) stddev-sans-first:76376.46 10:20:20 INFO - PROCESS | 12797 | Values: 382.4 386.0 395.9 400.1 412.2 12594.7 15911.9 16566.8 18183.0 18220.4 17027.4 18177.0 18486.7 18560.6 18792.0 5198.7 5358.1 5403.1 5410.4 5596.3 5644.9 5672.8 5697.6 5716.1 5726.2 5527.4 5683.7 5691.4 5703.7 5752.7 5660.1 5667.8 5715.2 5741.7 5749.5 5388.6 5528.5 5557.5 5577.8 5590.9 12849.6 12876.2 12983.7 13002.0 13016.9 2155.4 2184.1 2185.3 2221.5 2276.0 9563.1 9984.7 14267.5 14373.2 15274.7 1753.4 1780.3 1830.1 1849.5 1862.5 81007.6 84987.2 88462.0 89330.6 89508.0 17584.9 17827.9 17949.8 17987.6 18061.9 12302.1 33147.7 33188.3 33322.8 33324.7 6657.8 6708.9 6760.7 7015.4 7050.0 6388.6 6393.2 6584.8 6590.2 6614.2 3967.1 3979.4 4009.7 4137.3 4145.4 1974.7 1997.8 2003.7 2031.9 2040.8 897.2 922.5 923.5 926.7 932.1 16249.4 16482.0 17064.4 17463.3 17501.3 870.0 880.9 886.5 889.5 914.2 8829.6 9520.5 9528.0 9557.9 9641.1 453093.0 456998.0 462138.0 464944.0 465955.4 82291.2 83891.6 84211.0 84396.0 100492.5 944.5 967.9 990.0 995.1 1000.8 15051.0 15507.5 16503.7 16769.2 16837.6 31647.8 33113.8 37241.8 39624.4 39944.8 51850.0 53532.2 54548.7 55575.7 56159.0 6750.5 7808.8 7919.7 8034.2 8121.5 9485.9 11686.9 12118.5 12207.2 12214.7 9827.2 10050.7 10168.3 10280.6 10287.9 9785.0 9908.7 10193.6 10205.4 10614.4 9898.6 9976.5 10338.3 10399.8 10460.0 7091.8 7093.5 7117.0 7210.4 7418.2 9046.7 9603.8 9799.5 9924.3 10338.9 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | [#3] mootools.html Cycles:180 Average:4832.81 Median:4942.57 stddev:2697.69 (54.6%) stddev-sans-first:2692.52 10:20:20 INFO - PROCESS | 12797 | Values: 1344.4 1351.6 1351.9 1352.8 1357.9 7210.3 7290.9 7421.3 7674.1 7701.2 7460.6 7614.5 7621.4 7627.1 7637.0 1947.8 1990.0 1997.6 1997.9 2001.5 1968.7 2001.6 2032.3 2034.6 2041.6 1976.9 1988.1 1990.4 1999.3 2035.3 1953.1 1963.8 1966.1 1967.3 1974.2 1935.6 1990.1 1996.4 2005.6 2063.3 7626.8 7643.4 7685.6 7698.6 7747.2 4459.4 4599.1 4606.7 4609.9 4624.7 2670.7 2788.6 2806.4 2832.1 2836.2 1072.4 1088.1 1110.3 1127.1 1128.8 8989.1 9214.9 9919.6 9953.6 9987.6 3903.3 4019.6 4021.4 4058.0 4058.0 8173.8 8188.7 8225.8 8228.9 8247.7 4559.7 4580.7 4593.7 4600.6 4612.8 4176.9 4180.6 4182.4 4224.4 4288.8 3213.1 3256.6 3280.1 3290.1 3318.4 1010.6 1024.9 1038.8 1041.3 1041.9 612.4 629.2 633.8 641.0 645.2 4726.4 4741.1 4811.6 4924.1 5126.9 534.2 537.5 539.0 543.1 552.9 3153.8 3201.0 3220.0 3286.9 3378.6 8466.0 8667.6 8798.2 9007.3 9017.7 8800.0 8912.5 8950.0 8958.0 8964.1 6872.7 7087.3 7096.7 7099.8 7167.4 4869.7 4873.8 4961.0 4972.9 5130.5 7659.3 7962.1 8175.8 8250.2 8258.0 8150.8 8301.2 8358.5 8380.2 8443.5 7035.7 7131.3 7136.6 7139.9 7149.1 7023.3 7070.2 7118.5 7122.5 7146.7 6463.5 6463.6 6515.6 6544.0 6557.3 6261.7 6276.1 6287.5 6318.9 6322.2 5300.7 5369.9 5386.1 5437.4 5441.4 4150.3 4224.9 4311.0 4335.2 4423.0 4360.9 4361.7 4382.2 4457.0 4523.7 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | [#4] prototype.html Cycles:180 Average:4274.81 Median:4886.08 stddev:1231.50 (25.2%) stddev-sans-first:1199.50 10:20:20 INFO - PROCESS | 12797 | Values: 365.8 369.5 370.6 374.5 377.2 4847.8 4897.5 4949.5 4961.9 5004.7 4876.3 4944.1 4950.9 4959.0 4981.0 3755.2 3853.7 3891.8 3892.0 3910.3 3865.0 3867.5 3881.9 3903.4 3914.1 3877.7 3878.4 3878.6 3880.3 3881.6 3774.5 3805.6 3900.3 3907.6 3945.8 3384.9 3391.5 3396.3 3398.7 3408.8 4820.4 4869.5 4870.0 4894.5 4901.3 4428.5 4431.0 4439.2 4439.8 4441.4 4883.2 5170.0 5217.0 5234.6 5238.0 3708.0 3711.3 3712.7 3716.6 3724.3 5188.2 5297.2 5442.6 5474.2 5479.2 3632.4 3669.4 3674.3 3676.0 3706.0 3761.2 3770.8 3777.3 3786.2 3790.8 4343.0 4343.3 4391.3 4393.0 4400.4 5172.5 5253.3 5267.2 5276.6 5292.8 1287.6 1298.6 1298.7 1298.8 1298.8 821.8 883.5 892.8 892.9 903.2 4401.6 4420.6 4443.8 4444.2 4456.1 5170.4 5179.7 5192.4 5243.0 5256.3 4292.8 4503.3 4517.5 4522.0 4525.9 2735.9 2753.7 2759.4 2791.5 2793.5 4879.0 4881.8 4889.0 4891.5 4903.2 4994.7 5291.7 5295.8 5299.4 5332.0 4880.8 4904.9 4921.5 4922.5 4931.0 5229.6 5235.7 5239.3 5271.7 5273.7 4913.4 5148.5 5151.5 5158.8 5199.2 4833.4 4918.6 4930.3 4935.4 4938.0 3722.2 3779.6 3804.7 3806.9 3827.9 5323.4 5331.9 5344.6 5348.9 5380.4 4866.6 5158.5 5196.7 5223.8 5225.9 5119.7 5152.8 5169.5 5173.6 5196.7 5051.8 5065.6 5103.3 5107.2 5107.9 4986.5 5184.8 5188.4 5189.4 5211.3 5084.2 5084.3 5086.9 5098.5 5099.4 10:20:20 INFO - PROCESS | 12797 | 10:20:20 INFO - PROCESS | 12797 | [#5] yui.html Cycles:180 Average:14321.76 Median:24522.84 stddev:17626.14 (71.9%) stddev-sans-first:17645.62 10:20:20 INFO - PROCESS | 12797 | Values: 634.6 642.5 704.5 736.1 759.1 12790.8 13412.6 13575.3 13576.7 13618.8 11702.4 13339.3 13414.2 13469.6 13481.1 803.1 811.7 815.1 816.2 816.6 803.8 818.6 829.1 838.5 839.1 870.4 873.0 873.3 874.5 875.9 863.6 865.1 868.5 870.7 871.8 633.4 634.1 638.7 639.2 640.1 12581.4 12846.2 12881.7 13007.2 14469.6 13043.9 13184.0 13247.3 13354.0 13593.5 10226.0 10281.4 10467.5 10482.2 10543.5 3191.2 3191.6 3248.9 3249.4 3377.0 59638.4 60001.5 60876.0 61119.1 61904.7 10060.8 10139.8 10162.1 10281.0 10792.2 19318.2 19345.9 19360.7 19377.2 19409.4 6154.5 6359.2 6392.4 6562.9 6565.9 3287.6 3361.3 3407.5 3408.7 3473.2 2893.9 2919.5 2960.3 2962.6 2992.8 480.5 481.4 482.8 491.4 494.2 656.8 663.3 683.8 694.1 700.1 8808.3 8817.9 9068.6 9100.7 9472.7 468.8 486.0 490.2 492.5 505.2 4174.1 4275.6 4391.6 4426.9 4492.3 62563.0 62808.1 63160.4 64684.7 66171.3 42795.8 49814.3 53304.5 54742.2 55086.0 42549.0 51503.0 52615.0 53672.5 53699.0 8391.7 8575.4 8577.9 8717.2 8775.4 34790.0 35059.6 35374.0 35425.8 36580.8 42046.0 43787.8 43975.0 44553.3 46770.5 9825.3 10057.8 10119.1 10252.4 10363.6 11332.2 11463.0 11498.9 11517.3 11546.7 10144.0 10220.6 10316.6 10400.1 10496.2 10092.2 10155.3 10242.8 10354.9 10673.7 9999.0 10212.6 10343.9 10740.9 10745.7 8151.3 9027.6 9095.2 9237.2 9576.5 9337.9 9983.8 10685.4 10943.8 10995.6 10:20:20 INFO - PROCESS | 12797 | -------- Summary: end -------- 10:20:20 INFO - PROCESS | 12797 | 10:20:21 INFO - TEST-INFO | 12797: exit 0 10:20:21 INFO - TEST-OK | dromaeo_css | took 1120841ms 10:20:21 INFO - TEST-START | kraken 10:20:21 INFO - operating with platform_type : linux_ 10:20:21 INFO - Initialising browser for kraken test... 10:20:21 INFO - TEST-INFO | started process 13154 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV50GaE/profile http://localhost:55951/getInfo.html) 10:20:24 INFO - PROCESS | 13154 | Iterating over profile /tmp/tmpV50GaE/profile 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/extensions = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/localstore.rdf = Thu Feb 16 2017 10:00:59 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/places.sqlite-shm = Thu Feb 16 2017 10:20:22 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/pluginreg.dat = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/extensions.ini = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/prefs.js = Thu Feb 16 2017 10:00:59 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/content-prefs.sqlite = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/thumbnails = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/cache2 = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:25 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:25 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/places.sqlite-wal = Thu Feb 16 2017 10:20:25 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/mimeTypes.rdf = Thu Feb 16 2017 10:20:24 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/startupCache = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/compatibility.ini = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | __metrics Screen width/height:1600/1200 10:20:26 INFO - PROCESS | 13154 | colorDepth:24 10:20:26 INFO - PROCESS | 13154 | Browser inner width/height: 1024/697 10:20:26 INFO - PROCESS | 13154 | __metrics 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/permissions.sqlite = Thu Feb 16 2017 10:20:22 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/frequencyCap.json = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/user.js = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | Stat failure 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/minidumps = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/sessionCheckpoints.json = Thu Feb 16 2017 10:20:25 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/webappsstore.sqlite = Thu Feb 16 2017 10:20:25 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/secmod.db = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/safebrowsing = Thu Feb 16 2017 10:20:26 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/addons.json = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/blocklist.xml = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/extensions.json = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/key3.db = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/crashes = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/directoryLinks.json = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | Stat failure 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/cert8.db = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/gmp = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/bookmarkbackups = Thu Feb 16 2017 10:20:23 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/.parentlock = Thu Feb 16 2017 10:20:21 GMT-0800 (PST) 10:20:26 INFO - PROCESS | 13154 | No birth date. Using mtime. 10:20:26 INFO - PROCESS | 13154 | Using date: /tmp/tmpV50GaE/profile/places.sqlite = Thu Feb 16 2017 10:20:26 GMT-0800 (PST) 10:20:26 ERROR - PROCESS | 13154 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 10:20:27 INFO - TEST-INFO | 13154: exit 0 10:20:27 INFO - Browser initialized. 10:20:27 INFO - Running cycle 1/1 for kraken test... 10:20:27 INFO - TEST-INFO | started process 13274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV50GaE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:20:39 INFO - PROCESS | 13274 | RSS: Main: 160251904 10:20:39 INFO - PROCESS | 13274 | 10:20:42 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55951/tests/kraken/driver.html?testName=audio-beat-detection) 10:20:43 INFO - PROCESS | 13274 | RSS: Main: 166719488 10:20:43 INFO - PROCESS | 13274 | 10:20:46 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55951/tests/kraken/driver.html?testName=audio-dft) 10:20:47 INFO - PROCESS | 13274 | RSS: Main: 165310464 10:20:47 INFO - PROCESS | 13274 | 10:20:50 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55951/tests/kraken/driver.html?testName=audio-fft) 10:20:50 INFO - PROCESS | 13274 | RSS: Main: 162336768 10:20:50 INFO - PROCESS | 13274 | 10:20:52 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55951/tests/kraken/driver.html?testName=audio-oscillator) 10:20:53 INFO - PROCESS | 13274 | RSS: Main: 163250176 10:20:53 INFO - PROCESS | 13274 | 10:20:55 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55951/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:20:56 INFO - PROCESS | 13274 | RSS: Main: 163483648 10:20:56 INFO - PROCESS | 13274 | 10:20:59 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55951/tests/kraken/driver.html?testName=imaging-darkroom) 10:21:00 INFO - PROCESS | 13274 | RSS: Main: 168108032 10:21:00 INFO - PROCESS | 13274 | 10:21:03 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55951/tests/kraken/driver.html?testName=imaging-desaturate) 10:21:04 INFO - PROCESS | 13274 | RSS: Main: 164528128 10:21:04 INFO - PROCESS | 13274 | 10:21:07 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55951/tests/kraken/driver.html?testName=json-parse-financial) 10:21:07 INFO - PROCESS | 13274 | RSS: Main: 165675008 10:21:07 INFO - PROCESS | 13274 | 10:21:09 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55951/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:21:10 INFO - PROCESS | 13274 | RSS: Main: 165777408 10:21:10 INFO - PROCESS | 13274 | 10:21:12 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:21:12 INFO - PROCESS | 13274 | RSS: Main: 165597184 10:21:12 INFO - PROCESS | 13274 | 10:21:14 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:21:15 INFO - PROCESS | 13274 | RSS: Main: 166252544 10:21:15 INFO - PROCESS | 13274 | 10:21:18 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:21:19 INFO - PROCESS | 13274 | RSS: Main: 167260160 10:21:19 INFO - PROCESS | 13274 | 10:21:22 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:21:23 INFO - PROCESS | 13274 | RSS: Main: 169697280 10:21:23 INFO - PROCESS | 13274 | 10:21:24 INFO - PROCESS | 13274 | Cycle 1(1): loaded http://localhost:55951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55951/tests/kraken/driver.html?testName=ai-astar) 10:21:25 INFO - PROCESS | 13274 | RSS: Main: 170827776 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | __start_tp_report 10:21:25 INFO - PROCESS | 13274 | _x_x_mozilla_page_load 10:21:25 INFO - PROCESS | 13274 | _x_x_mozilla_page_load_details 10:21:25 INFO - PROCESS | 13274 | |i|pagename|runs| 10:21:25 INFO - PROCESS | 13274 | |0;ai-astar;109;115;117;115;115;117;116;114;115;118 10:21:25 INFO - PROCESS | 13274 | |1;audio-beat-detection;158;137;134;183;141;135;130;128;151;127 10:21:25 INFO - PROCESS | 13274 | |2;audio-dft;164;176;171;169;167;164;163;165;164;165 10:21:25 INFO - PROCESS | 13274 | |3;audio-fft;77;76;77;106;81;81;78;77;99;78 10:21:25 INFO - PROCESS | 13274 | |4;audio-oscillator;85;85;106;84;85;87;107;85;84;113 10:21:25 INFO - PROCESS | 13274 | |5;imaging-gaussian-blur;115;114;115;114;115;116;113;114;114;115 10:21:25 INFO - PROCESS | 13274 | |6;imaging-darkroom;150;153;150;151;150;153;151;155;150;156 10:21:25 INFO - PROCESS | 13274 | |7;imaging-desaturate;97;97;96;96;98;97;97;96;96;96 10:21:25 INFO - PROCESS | 13274 | |8;json-parse-financial;65;68;66;65;66;66;67;67;66;68 10:21:25 INFO - PROCESS | 13274 | |9;json-stringify-tinderbox;66;66;65;65;66;68;66;66;65;66 10:21:25 INFO - PROCESS | 13274 | |10;stanford-crypto-aes;87;88;93;89;89;89;91;88;92;87 10:21:25 INFO - PROCESS | 13274 | |11;stanford-crypto-ccm;149;139;140;145;147;152;149;155;190;148 10:21:25 INFO - PROCESS | 13274 | |12;stanford-crypto-pbkdf2;194;198;188;188;190;189;185;192;189;184 10:21:25 INFO - PROCESS | 13274 | |13;stanford-crypto-sha256-iterative;77;71;75;73;77;73;73;71;73;73 10:21:25 INFO - PROCESS | 13274 | __end_tp_report 10:21:25 INFO - PROCESS | 13274 | __start_cc_report 10:21:25 INFO - PROCESS | 13274 | _x_x_mozilla_cycle_collect,891 10:21:25 INFO - PROCESS | 13274 | __end_cc_report 10:21:25 INFO - PROCESS | 13274 | __startTimestamp1487269285153__endTimestamp 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | ------- Summary: start ------- 10:21:25 INFO - PROCESS | 13274 | Number of tests: 14 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:2.47 (2.1%) stddev-sans-first:1.30 10:21:25 INFO - PROCESS | 13274 | Values: 109.0 115.0 117.0 115.0 115.0 117.0 116.0 114.0 115.0 118.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:139.00 stddev:17.39 (12.5%) stddev-sans-first:17.50 10:21:25 INFO - PROCESS | 13274 | Values: 158.0 137.0 134.0 183.0 141.0 135.0 130.0 128.0 151.0 127.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#2] audio-dft Cycles:10 Average:166.80 Median:166.00 stddev:4.10 (2.5%) stddev-sans-first:4.23 10:21:25 INFO - PROCESS | 13274 | Values: 164.0 176.0 171.0 169.0 167.0 164.0 163.0 165.0 164.0 165.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#3] audio-fft Cycles:10 Average:83.00 Median:78.00 stddev:10.54 (13.5%) stddev-sans-first:10.95 10:21:25 INFO - PROCESS | 13274 | Values: 77.0 76.0 77.0 106.0 81.0 81.0 78.0 77.0 99.0 78.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:85.00 stddev:11.60 (13.6%) stddev-sans-first:12.02 10:21:25 INFO - PROCESS | 13274 | Values: 85.0 85.0 106.0 84.0 85.0 87.0 107.0 85.0 84.0 113.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:0.85 (0.7%) stddev-sans-first:0.88 10:21:25 INFO - PROCESS | 13274 | Values: 115.0 114.0 115.0 114.0 115.0 116.0 113.0 114.0 114.0 115.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#6] imaging-darkroom Cycles:10 Average:151.90 Median:152.00 stddev:2.23 (1.5%) stddev-sans-first:2.26 10:21:25 INFO - PROCESS | 13274 | Values: 150.0 153.0 150.0 151.0 150.0 153.0 151.0 155.0 150.0 156.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:97.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 10:21:25 INFO - PROCESS | 13274 | Values: 97.0 97.0 96.0 96.0 98.0 97.0 97.0 96.0 96.0 96.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:1.01 10:21:25 INFO - PROCESS | 13274 | Values: 65.0 68.0 66.0 65.0 66.0 66.0 67.0 67.0 66.0 68.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 10:21:25 INFO - PROCESS | 13274 | Values: 66.0 66.0 65.0 65.0 66.0 68.0 66.0 66.0 65.0 66.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:89.00 stddev:2.06 (2.3%) stddev-sans-first:2.01 10:21:25 INFO - PROCESS | 13274 | Values: 87.0 88.0 93.0 89.0 89.0 89.0 91.0 88.0 92.0 87.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:149.00 stddev:14.42 (9.7%) stddev-sans-first:15.26 10:21:25 INFO - PROCESS | 13274 | Values: 149.0 139.0 140.0 145.0 147.0 152.0 149.0 155.0 190.0 148.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.70 Median:189.50 stddev:4.14 (2.2%) stddev-sans-first:4.09 10:21:25 INFO - PROCESS | 13274 | Values: 194.0 198.0 188.0 188.0 190.0 189.0 185.0 192.0 189.0 184.0 10:21:25 INFO - PROCESS | 13274 | 10:21:25 INFO - PROCESS | 13274 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:73.00 stddev:2.12 (2.9%) stddev-sans-first:1.86 10:21:25 INFO - PROCESS | 13274 | Values: 77.0 71.0 75.0 73.0 77.0 73.0 73.0 71.0 73.0 73.0 10:21:25 INFO - PROCESS | 13274 | -------- Summary: end -------- 10:21:25 INFO - PROCESS | 13274 | 10:21:26 INFO - TEST-INFO | 13274: exit 0 10:21:26 INFO - TEST-OK | kraken | took 65172ms 10:21:26 INFO - SUITE-END | took 1186s 10:21:26 INFO - Completed test suite (00:19:46) 10:21:26 INFO - javascript benchmark 10:21:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6306.359189313475, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2608.1393140263713, 2620.921372358829, 2624.4882247961655, 2649.5495765719834, 2661.1218297961673, 4655.720656760596, 4737.336772851803, 4788.760561971896, 4795.68828026178, 4798.448178459478, 4715.03341814928, 4721.504242054586, 4742.7865746041425, 4753.47711751707, 4809.831655892044, 4153.937690934638, 4335.848245311415, 4383.561643835623, 4446.666500012496, 4518.299663552139, 4425.933610995821, 4431.113777244548, 4472.53038430965, 4482.9551704482865, 4527.796249168795, 4237.915241695179, 4269.743815371087, 4335.414719012924, 4369.541198174178, 4404.229259879508, 4396.1427521633295, 4396.582324679151, 4412.911741765147, 4442.489113751928, 4444.377787109807, 4343.348497725335, 4379.8467053653285, 4386.978065109654, 4416.80124394403, 4467.419235499397, 4560.8859778505475, 4589.196890544142, 4645.256758918591, 4720.457147428021, 4774.069056533977, 5117.155669314596, 5118.360204974379, 5122.8463146105305, 5166.405863325728, 5176.5858731301405, 5156.793728250866, 5157.174852023658, 5158.148905430637, 5186.818461353872, 5228.215767634847, 4689.577937985598, 4692.202325604655, 4731.266653668687, 4746.240601503743, 4820.0841840049925, 5005.224190250517, 5174.146265866165, 5192.039472697487, 5208.6874787512115, 5252.474752524717, 4861.611071114302, 4898.510148985073, 4903.043906438282, 4905.386826646669, 4922.753862306942, 4969.676970996874, 5028.899422011539, 5050.116229659795, 5090.465501122403, 5128.487151284841, 5026.648134630541, 5065.189569668835, 5080.949190508121, 5096.337476128079, 5132.8460146195675, 4910.508949105061, 4927.457979622239, 4949.257611358325, 4972.776225069881, 5070.416902056237, 4288.185244803477, 4296.720713153635, 4315.28797748368, 4317.56824317572, 4341.696081274344, 3706.5181526401393, 3734.79458629778, 3749.8687545935763, 3778.131029863147, 3792.6586607205486, 4362.9965108025335, 4374.671899607542, 4414.6247568957, 4440.0, 4440.444944381952, 5015.27278544609, 5039.924401133986, 5063.8987220255385, 5093.796248150033, 5104.2343648453025, 4400.933985990213, 4407.228734971432, 4407.2507673695545, 4409.801558929856, 4442.755648439367, 4506.414166158378, 4548.113117942035, 4549.863504094882, 4565.703229290086, 4585.472670642852, 4752.786124624469, 4764.33299338086, 4765.8570242892765, 4783.115123702126, 4810.8797280068275, 5087.491250874883, 5091.567216786615, 5091.923621145612, 5093.108705976365, 5130.281760553451, 4676.438827340741, 4771.642126840431, 4775.95224047769, 4797.808087676453, 4824.324594556835, 4922.753862306942, 5002.224655178453, 5010.223415370623, 5031.421386540448, 5098.515641014098, 5117.667351622133, 5129.435762066244, 5133.127368347465, 5137.5889928806355, 5140.306058682179, 4767.356406885026, 4820.68665536739, 4828.8792780180775, 4847.5394837490385, 5017.523335283142, 4827.372441582572, 4844.0554091953145, 4853.053654537332, 4859.659823812299, 4860.562549370574, 4777.378940737682, 4783.928241076317, 4800.303955926441, 4808.0, 4815.927761083516, 4849.684770489906, 4878.512148785093, 4881.658283920234, 4899.240617704261, 4933.383327084114, 4816.0, 4823.324734537097, 4823.903521929612, 4831.95168048315, 4838.153323168361], "value": 4650.63646700984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.7051945131548, 516.150931717325, 529.9390570084441, 539.8677324055602, 544.1674238415225, 38255.617443825555, 39296.41055384168, 39420.211595768065, 39514.209715805664, 42995.14009719811, 38297.425538616946, 38515.61484385155, 38939.610603893954, 39030.609693903054, 39105.0, 7596.3922886168975, 7774.80562985927, 7775.30022297993, 9191.35660503765, 9534.094261045224, 9252.537373131316, 9330.626774928995, 9347.065293470667, 9354.672586459475, 9356.672516461926, 9311.255099592016, 9319.394239374453, 9340.532973351339, 9347.485888276142, 9349.345545811795, 9269.351145419823, 9285.535723213847, 9307.301952353533, 9324.440533567973, 9349.76625584359, 9103.36276460648, 9125.817483650357, 9145.634174633007, 9148.405353651991, 9163.08369163085, 32796.18009549756, 33057.33885322304, 33129.17177070568, 33306.83346583276, 33337.3332533348, 3408.642092580268, 3519.225770330523, 3543.539339885824, 3559.928801423957, 3588.3540962626716, 25334.366640834127, 25369.11208107726, 25400.745992540207, 25522.23433297004, 25666.87166564174, 3401.829908504565, 3447.793132412063, 3473.322702073097, 3611.422172452395, 3630.709543236535, 225055.0, 225632.0, 225792.0, 226262.0, 226610.0, 38578.0, 39471.40792888109, 45276.54723452789, 45793.5420645796, 46194.53805461902, 45940.77029614831, 46250.0, 46274.30588541175, 46347.53652463432, 46410.303845442344, 11689.473973671205, 11707.882921170849, 11712.238704484182, 11719.707007324885, 11826.645200643994, 9014.819703606023, 9167.44995300284, 9174.53207486799, 9174.908250917406, 9177.357584968988, 15784.763228551581, 15797.44557474559, 15842.28709708066, 15842.76235856463, 15884.60288492797, 6487.767324208386, 6529.477641788645, 6599.340065993362, 6607.702653380609, 6625.0, 774.825664225556, 787.7597332813547, 791.877259024852, 795.5902710104301, 816.3061397811813, 25935.22194334173, 27109.0, 27165.728342716717, 27404.588931166047, 27651.86174069117, 948.5257371314343, 950.092661508258, 953.1659797677032, 954.3891909178132, 1095.775366049958, 14297.92851035759, 14472.710545789025, 14485.348159332856, 14575.41698332076, 14668.339924703414, 318993.0, 321886.0, 322696.0, 323286.0, 324613.0, 49047.50952490501, 49217.0, 49335.59966005056, 49336.75331623391, 49429.50570494321, 1145.495981768036, 1153.3541216918552, 1176.2883455509464, 1177.1700950829472, 1181.1849823621674, 24620.0, 25576.744232557437, 26757.464850702487, 26853.462930741665, 26930.0, 41144.38283425691, 41214.38178427266, 41438.79280603577, 41490.58509414867, 41598.792006039774, 271436.0, 273607.0, 273933.0, 274087.0, 275971.0, 24183.15358962428, 24975.125870594442, 24977.12580059762, 24990.126135493923, 25064.74935250697, 39950.800245999744, 40501.1899762009, 40848.1830363397, 41264.1747165061, 41378.0, 33915.830420847735, 34300.31399372048, 34417.0, 34632.30735385228, 34738.47892281567, 33010.0, 33320.833395832866, 33391.0, 33872.66127338794, 34183.82908085443, 30068.849655751583, 34066.0, 34281.828590857884, 34362.656373435944, 34493.0, 23719.644205337278, 24258.878705607065, 26938.32654183661, 27747.58378624282, 28803.423931520832, 22547.661785073564, 22557.774422256225, 22616.773832262126, 22619.43451413662, 23038.884805576534], "value": 16700.33652993055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.38627003659144, 386.0329873666465, 395.89112993926665, 400.135706873154, 412.1859327827541, 12594.685132871682, 15911.920440397811, 16566.751498727528, 18183.0, 18220.362287319946, 17027.404040858542, 18177.0, 18486.722699159523, 18560.628787424244, 18792.0, 5198.714070726099, 5358.089124848766, 5403.135498320269, 5410.404855465896, 5596.3284405871145, 5644.915326270088, 5672.801451949183, 5697.6011679182475, 5716.085426331788, 5726.198332233491, 5527.364353099385, 5683.687397193174, 5691.430856914317, 5703.74333155009, 5752.74112664929, 5660.066089095315, 5667.801626943037, 5715.228444160027, 5741.712914354265, 5749.453801888814, 5388.649737767072, 5528.5024347808485, 5557.527610153142, 5577.8047768328315, 5590.91613625796, 12849.614511564669, 12876.163049401759, 12983.675408114777, 13002.0, 13016.869831301661, 2155.353393981815, 2184.137265780008, 2185.267935241696, 2221.4557433428754, 2276.0440614941763, 9563.09150630689, 9984.700458986243, 14267.500637477742, 14373.209473478955, 15274.694506109927, 1753.4301352060631, 1780.2789870102629, 1830.1056863642686, 1849.4636555398918, 1862.4878158506383, 81007.5949620254, 84987.15012849915, 88462.0, 89330.55334723285, 89508.0, 17584.912075439795, 17827.910860445616, 17949.820501795075, 17987.640247194984, 18061.90969045173, 12302.138850280395, 33147.66852331494, 33188.34928157901, 33322.83338583291, 33324.66675333264, 6657.833554161185, 6708.899366509506, 6760.695768690322, 7015.403690686247, 7049.977753225856, 6388.552801303864, 6393.1688880445245, 6584.835379115465, 6590.242122155918, 6614.173228346457, 3967.107400834789, 3979.422983667352, 4009.67922566194, 4137.338025915839, 4145.398917156996, 1974.7334109895266, 1997.8401727861735, 2003.6994450832492, 2031.9085641146185, 2040.7857174996411, 897.228383590112, 922.460360688991, 923.4782347973508, 926.7080869526078, 932.1470854168496, 16249.431269905497, 16482.0, 17064.402745903833, 17463.301467941394, 17501.29994800194, 869.9782505437415, 880.9251213646912, 886.4548302793736, 889.4929889962659, 914.2274777812737, 8829.60266787996, 9520.476373799369, 9528.0, 9557.856632150524, 9641.084097010913, 453093.0, 456998.0, 462138.0, 464944.0, 465955.36178553157, 82291.17708822955, 83891.58054209812, 84211.0, 84396.0, 100492.49753751184, 944.5135755086136, 967.888692800337, 989.9852651032749, 995.1491474789096, 1000.849872519128, 15050.973308250112, 15507.457238996582, 16503.66992660164, 16769.245383957994, 16837.579060523585, 31647.84176079105, 33113.834430827694, 37241.81379093087, 39624.40563391609, 39944.80027599844, 51850.0, 53532.197017043996, 54548.72725636346, 55575.72212138913, 56159.0, 6750.5274630775375, 7808.84382312362, 7919.703286046982, 8034.196580341919, 8121.512709237465, 9485.905140948502, 11686.88313116892, 12118.515259389525, 12207.206531575419, 12214.694632634255, 9827.213822894008, 10050.748731281485, 10168.288219824837, 10280.588776448858, 10287.948560257151, 9784.951075244579, 9908.653197138066, 10193.592256309665, 10205.38767673942, 10614.363138211424, 9898.624330160854, 9976.451295178835, 10338.276320657482, 10399.792004159723, 10460.0, 7091.787246382617, 7093.538919970186, 7117.0, 7210.387117095106, 7418.18399976013, 9046.68336608242, 9603.759906002406, 9799.461029643438, 9924.255680823822, 10338.896611033793], "value": 8726.9728851489, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.435337158394, 1351.641814919046, 1351.9121257118293, 1352.8038434427008, 1357.884579810716, 7210.315020073092, 7290.927090729091, 7421.294976977192, 7674.07911050674, 7701.229877012303, 7460.552366857993, 7614.543127412359, 7621.428392870529, 7627.0847498300145, 7637.0, 1947.7662680478327, 1990.044778506318, 1997.590493948737, 1997.850161237913, 2001.5196352875278, 1968.7046942958527, 2001.5996800639857, 2032.2886989553685, 2034.6235946349973, 2041.5916816636732, 1976.8517361197896, 1988.1152886965306, 1990.4227773945538, 1999.2802591067277, 2035.3181684135832, 1953.0722906619385, 1963.8232559069752, 1966.1054220329715, 1967.2819420911335, 1974.2103158736477, 1935.6225536020481, 1990.1143990924045, 1996.371143089922, 2005.6490114230098, 2063.298478517296, 7626.8474630507635, 7643.350315223212, 7685.615719214017, 7698.576578288192, 7747.225277472207, 4459.35339375792, 4599.149157405891, 4606.677532572721, 4609.9308510372375, 4624.722516649012, 2670.6661667291587, 2788.5956536302324, 2806.3685670724126, 2832.107886015898, 2836.163331817119, 1072.3887384191014, 1088.1131877519797, 1110.3337997201616, 1127.1039523578775, 1128.7686024364984, 8989.101089890959, 9214.861777073349, 9919.603215871357, 9953.601855925754, 9987.550560224807, 3903.316919539069, 4019.638232559084, 4021.4369988201674, 4058.0, 4058.0463591056077, 8173.836523269621, 8188.672453101928, 8225.848381226633, 8228.917710822934, 8247.711330103417, 4559.726416415026, 4580.65645076614, 4593.655475839326, 4600.608948239437, 4612.7924243409125, 4176.937345939813, 4180.623743863006, 4182.393552934807, 4224.429701990253, 4288.764117973541, 3213.084270982759, 3256.5766450361302, 3280.114369120324, 3290.1445624137896, 3318.419276626629, 1010.5907107621425, 1024.8718910136233, 1038.8285932821152, 1041.3231399590175, 1041.8541404203418, 612.4396177497629, 629.1946308724839, 633.7591715148214, 640.9840402961265, 645.2321737132797, 4726.432828060655, 4741.051789642085, 4811.615070794328, 4924.138275801721, 5126.897462050738, 534.2120372450682, 537.484015345273, 538.9514943655012, 543.0957455836068, 552.9364123125812, 3153.7857924698696, 3201.0, 3219.951700724491, 3286.8520916558814, 3378.5607870976614, 8466.0, 8667.566621669017, 8798.164174403422, 9007.324450666096, 9017.684381046764, 8799.956000219958, 8912.465252084896, 8949.955250223707, 8958.0, 8964.14840590143, 6872.725090996404, 7087.2558381370245, 7096.680649370791, 7099.82250443743, 7167.354938055496, 4869.6591238612955, 4873.780679869344, 4961.0, 4972.8508144755715, 5130.461301563431, 7659.348955338635, 7962.084360298408, 8175.754727358189, 8250.174982501701, 8258.009038915368, 8150.796230094058, 8301.211384918422, 8358.498490090615, 8380.16198380157, 8443.451175673561, 7035.718571257297, 7131.286871312827, 7136.607486588287, 7139.8572028560175, 7149.14210294768, 7023.33278338557, 7070.222275549789, 7118.466115041289, 7122.465815063994, 7146.678399471933, 6463.450606698483, 6463.64449955257, 6515.641639709672, 6543.967280163569, 6557.311482294481, 6261.655608941552, 6276.1213430118905, 6287.528435367274, 6318.905216421849, 6322.178116844781, 5300.70846103468, 5369.892602148013, 5386.111291636836, 5437.4018857926385, 5441.374241962225, 4150.27370210218, 4224.91550169001, 4311.0, 4335.157558037884, 4422.955770442383, 4360.890977725456, 4361.716488428242, 4382.23310920594, 4457.0194557197365, 4523.660725445539], "value": 3845.0559169688013, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.8408592262366, 369.5473045519239, 370.5775416025729, 374.5093926955688, 377.15140932900954, 4847.830325938593, 4897.485763994779, 4949.52979466951, 4961.933184365361, 5004.749762511878, 4876.292937524066, 4944.145945270524, 4950.900981980359, 4958.950410495894, 4981.0, 3755.155090104718, 3853.65317121459, 3891.8443262269475, 3891.995622254541, 3910.335243008695, 3865.033741564609, 3867.4972253606993, 3881.8641347552843, 3903.355946268862, 3914.1388894443176, 3877.7091718121114, 3878.3794592865147, 3878.5733569307495, 3880.301545721769, 3881.602623055712, 3774.5470543534675, 3805.6384643458828, 3900.3369427197445, 3907.6483116519507, 3945.7829819360068, 3384.881529146468, 3391.5082313064468, 3396.303757729684, 3398.745094117951, 3408.812515311657, 4820.373351464323, 4869.5130486951375, 4870.0, 4894.5105489451125, 4901.289313049588, 4428.512863585003, 4431.0, 4439.156560253574, 4439.800208990603, 4441.400410944546, 4883.169861123618, 5169.966006798618, 5216.9739151304, 5234.607404444682, 5237.973810130963, 3707.9814600927093, 3711.2948539777626, 3712.7215458840424, 3716.646918542734, 3724.255148970217, 5188.247704082887, 5297.212493067436, 5442.57805276652, 5474.178873169017, 5479.205515200274, 3632.4006538921312, 3669.3945498992903, 3674.283514714605, 3675.9632403676155, 3706.0, 3761.153740408441, 3770.830312635938, 3777.3011992780876, 3786.1670432504807, 3790.8483660653815, 4342.956570434319, 4343.261645520269, 4391.253486907233, 4393.0, 4400.383946247528, 5172.482751724798, 5253.290805741252, 5267.157254839208, 5276.577873770089, 5292.841214763564, 1287.575100216926, 1298.6298904812083, 1298.6753311672082, 1298.8310520531597, 1298.8311519502404, 821.7904434369198, 883.4522595990527, 892.7857314244535, 892.9374943754004, 903.214203642835, 4401.581849724271, 4420.624246938982, 4443.844465443694, 4444.2222611042935, 4456.128426061062, 5170.353705786777, 5179.720922794034, 5192.429747275123, 5242.9814521409535, 5256.316678831728, 4292.800750566404, 4503.343027613723, 4517.480489743656, 4521.977390113028, 4525.932111018337, 2735.9042433514733, 2753.6695596528543, 2759.448110377892, 2791.4975304445393, 2793.469240844233, 4879.04838064777, 4881.803958030234, 4888.9955118401285, 4891.535304146171, 4903.190973489405, 4994.676504815891, 5291.708956007379, 5295.788168473295, 5299.364076310791, 5332.0, 4880.804767809319, 4904.8773780655765, 4921.548143297736, 4922.4585295616735, 4930.975345123252, 5229.555487783581, 5235.712035838065, 5239.345081864767, 5271.710055946806, 5273.6572122811895, 4913.434954980223, 5148.536631703165, 5151.5363617274625, 5158.793648254028, 5199.220116982483, 4833.3958255218095, 4918.6065114791445, 4930.285108659153, 4935.407751069751, 4937.975310123426, 3722.1997270586953, 3779.5842457330223, 3804.6575808177395, 3806.9238615228096, 3827.88991192551, 5323.414424413234, 5331.920021199763, 5344.572434205176, 5348.893022139613, 5380.408155102858, 4866.610671146371, 5158.509941555546, 5196.674847913758, 5223.7910483580235, 5225.869353266199, 5119.74401279942, 5152.819651312186, 5169.53474187325, 5173.611979101658, 5196.68819870809, 5051.772670229775, 5065.56942659878, 5103.285540024473, 5107.233914912644, 5107.897842043212, 4986.451490336132, 5184.766685499086, 5188.377394712658, 5189.4032186299055, 5211.2704221409795, 5084.186530155157, 5084.339035925454, 5086.872828179326, 5098.46466121052, 5099.439061703136], "value": 3900.2263084359292, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.6160572853421, 642.4924309795259, 704.4751660013287, 736.1129838544555, 759.0739298056371, 12790.808137877928, 13412.59762207133, 13575.321233938314, 13576.728465430686, 13618.795718064224, 11702.356370399624, 13339.333033348343, 13414.19514829111, 13469.595912122628, 13481.123726957765, 803.140639515717, 811.7240138352959, 815.0504662068682, 816.1552792859392, 816.6447595296036, 803.8352137811764, 818.5743413425015, 829.1459796409707, 838.4591938199887, 839.1273076000952, 870.3602851903846, 873.0178549132227, 873.3493547307265, 874.510274246424, 875.8554838451648, 863.6459051788736, 865.0614083719172, 868.5049521772593, 870.6778491957984, 871.8169184471269, 633.37612451735, 634.0552576660742, 638.7125793392945, 639.2456900856987, 640.0718957511626, 12581.370931453483, 12846.164999275017, 12881.67795805103, 13007.154534955293, 14469.565913022627, 13043.86956130436, 13184.0, 13247.271400072992, 13354.0, 13593.456261749518, 10226.0, 10281.434521101337, 10467.476626168736, 10482.213833962483, 10543.472826358651, 3191.212920764356, 3191.6170059592996, 3248.886288979898, 3249.415105281072, 3376.983115084409, 59638.40361596372, 60001.499962501286, 60875.999999999556, 61119.08321375183, 61904.69047654733, 10060.849087263698, 10139.847902281472, 10162.136218421518, 10281.0, 10792.190585706103, 19318.227270909007, 19345.90327048356, 19360.70958935617, 19377.224911003403, 19409.4177174685, 6154.5076393888385, 6359.2368915729485, 6392.3927226913365, 6562.934370656328, 6565.901511477332, 3287.5561799157285, 3361.277325375056, 3407.522946787452, 3408.7102596279096, 3473.235888104613, 2893.8842446302333, 2919.547470142089, 2960.304328482805, 2962.6444826620514, 2992.820430774117, 480.4522843957891, 481.4222932481037, 482.80929033031754, 491.40785353648766, 494.2043310270462, 656.8062421585644, 663.290279401046, 683.8187880211748, 694.0664805836142, 700.1143553404946, 8808.251298639687, 8817.867731984026, 9068.591913363915, 9100.681476148304, 9472.715818525456, 468.79607370793815, 486.0, 490.2376804069706, 492.48535280631825, 505.2345696270154, 4174.102567947845, 4275.636570891447, 4391.560843915583, 4426.911461770746, 4492.348609451611, 62563.0, 62808.05787913185, 63160.36839631637, 64684.67657661682, 66171.33828661748, 42795.786021069696, 49814.252786208235, 53304.46695532995, 54742.17886731702, 55086.0, 42549.0, 51503.0, 52615.0, 53672.463275367525, 53699.0, 8391.706290279812, 8575.356848236532, 8577.871331930151, 8717.171868672465, 8775.385722999328, 34790.0, 35059.64940350564, 35374.0, 35425.82287088548, 36580.81709591435, 42046.0, 43787.78106109449, 43975.0, 44553.33170002518, 46770.53229467662, 9825.312228144247, 10057.798844022933, 10119.139873110867, 10252.43611601369, 10363.585456581652, 11332.197649033362, 11463.0, 11498.885011149781, 11517.25137866036, 11546.711332216762, 10144.0, 10220.642277520252, 10316.638917637847, 10400.115990140923, 10496.160307175254, 10092.243081769044, 10155.339902906288, 10242.795144096926, 10354.896451035393, 10673.733156671145, 9999.0, 10212.591496340063, 10343.94828025855, 10740.892591073989, 10745.731356716145, 8151.30713889326, 9027.638894444148, 9095.226905713089, 9237.214836738953, 9576.521173941135, 9337.859932100888, 9983.800323993624, 10685.358878467317, 10943.796182420087, 10995.615153469591], "value": 6187.683349530676, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1598.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 117.0, 115.0, 115.0, 117.0, 116.0, 114.0, 115.0, 118.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 137.0, 134.0, 183.0, 141.0, 135.0, 130.0, 128.0, 151.0, 127.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 176.0, 171.0, 169.0, 167.0, 164.0, 163.0, 165.0, 164.0, 165.0], "value": 166.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 106.0, 81.0, 81.0, 78.0, 77.0, 99.0, 78.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 106.0, 84.0, 85.0, 87.0, 107.0, 85.0, 84.0, 113.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 114.0, 115.0, 116.0, 113.0, 114.0, 114.0, 115.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 150.0, 151.0, 150.0, 153.0, 151.0, 155.0, 150.0, 156.0], "value": 151.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 96.0, 98.0, 97.0, 97.0, 96.0, 96.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 66.0, 65.0, 66.0, 66.0, 67.0, 67.0, 66.0, 68.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 68.0, 66.0, 66.0, 65.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 93.0, 89.0, 89.0, 89.0, 91.0, 88.0, 92.0, 87.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 139.0, 140.0, 145.0, 147.0, 152.0, 149.0, 155.0, 190.0, 148.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 198.0, 188.0, 188.0, 190.0, 189.0, 185.0, 192.0, 189.0, 184.0], "value": 189.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 75.0, 73.0, 77.0, 73.0, 73.0, 71.0, 73.0, 73.0], "value": 73.6, "unit": "score"}]}]} 10:21:26 INFO - Return code: 0 10:21:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:21:26 ERROR - # TBPL SUCCESS # 10:21:26 INFO - Running post-action listener: _resource_record_post_action 10:21:26 INFO - [mozharness: 2017-02-16 18:21:26.982876Z] Finished run-tests step (success) 10:21:26 INFO - Running post-run listener: _resource_record_post_run 10:21:27 INFO - Total resource usage - Wall time: 1203s; CPU: 14.0%; Read bytes: 0; Write bytes: 403673088; Read time: 0; Write time: 384092 10:21:27 INFO - TinderboxPrint: CPU usage
13.5% 10:21:27 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 10:21:27 INFO - TinderboxPrint: I/O write bytes / time
403,673,088 / 384,092 10:21:27 INFO - TinderboxPrint: CPU idle
8,302.7 (86.5%) 10:21:27 INFO - TinderboxPrint: CPU user
1,236.5 (12.9%) 10:21:27 INFO - TinderboxPrint: Swap in / out
0 / 0 10:21:27 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 107548672; Read time: 0; Write time: 137536 10:21:27 INFO - run-tests - Wall time: 1187s; CPU: 14.0%; Read bytes: 0; Write bytes: 296124416; Read time: 0; Write time: 246556 10:21:27 INFO - Running post-run listener: _upload_blobber_files 10:21:27 INFO - Blob upload gear active. 10:21:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:21:27 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 10:21:27 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:21:27 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:21:28 INFO - (blobuploader) - INFO - Open directory for files ... 10:21:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:21:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:21:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:31 INFO - (blobuploader) - INFO - Done attempting. 10:21:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:21:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:21:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:32 INFO - (blobuploader) - INFO - Done attempting. 10:21:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:21:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:21:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:33 INFO - (blobuploader) - INFO - Done attempting. 10:21:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:21:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:21:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:33 INFO - (blobuploader) - INFO - Done attempting. 10:21:33 INFO - (blobuploader) - INFO - Iteration through files over. 10:21:33 INFO - Return code: 0 10:21:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:21:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:21:33 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ca62f93ee1fac236ea31c2d646a37928f3016658d05c92784e40ec1eb0143f9353ab5872eabb53b84a1989a71ca11f917516e4970a06131b40cb0f5d6f8446b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f692283fd6e1d5a750f514519ecc5d533c9b92d4c06d9ad6069de62c52fedcd546e3bad057868242bb7c2901c327df705c6dbc61f0463a82a41cf52a286de4ab", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/38977f6927a0625fcd269ffd2c29df47379b435ca2d2e8639b777e4bc51a3e95682638a64ab0dc895802cc6e796ecaeef71e121d39c7198ae7c8104c10aecf55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6d944618a2239259ba92bcded4b4a9a4d269f45116ce30a1c34df5496217803e7f38a4cd0d51909537baf5d3e40067789c42ecb9e4cc28af41ef9741f661d44"} 10:21:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:21:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:21:34 INFO - Contents: 10:21:34 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ca62f93ee1fac236ea31c2d646a37928f3016658d05c92784e40ec1eb0143f9353ab5872eabb53b84a1989a71ca11f917516e4970a06131b40cb0f5d6f8446b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f692283fd6e1d5a750f514519ecc5d533c9b92d4c06d9ad6069de62c52fedcd546e3bad057868242bb7c2901c327df705c6dbc61f0463a82a41cf52a286de4ab", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/38977f6927a0625fcd269ffd2c29df47379b435ca2d2e8639b777e4bc51a3e95682638a64ab0dc895802cc6e796ecaeef71e121d39c7198ae7c8104c10aecf55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6d944618a2239259ba92bcded4b4a9a4d269f45116ce30a1c34df5496217803e7f38a4cd0d51909537baf5d3e40067789c42ecb9e4cc28af41ef9741f661d44"} 10:21:34 INFO - Running post-run listener: copy_logs_to_upload_dir 10:21:34 INFO - Copying logs to upload dir... 10:21:34 INFO - mkdir: /builds/slave/test/build/upload/logs 10:21:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.879570 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-16 10:21:33.526174) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 10:21:33.529795) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166 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-Non-PGO/sha512/8ca62f93ee1fac236ea31c2d646a37928f3016658d05c92784e40ec1eb0143f9353ab5872eabb53b84a1989a71ca11f917516e4970a06131b40cb0f5d6f8446b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f692283fd6e1d5a750f514519ecc5d533c9b92d4c06d9ad6069de62c52fedcd546e3bad057868242bb7c2901c327df705c6dbc61f0463a82a41cf52a286de4ab", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/38977f6927a0625fcd269ffd2c29df47379b435ca2d2e8639b777e4bc51a3e95682638a64ab0dc895802cc6e796ecaeef71e121d39c7198ae7c8104c10aecf55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6d944618a2239259ba92bcded4b4a9a4d269f45116ce30a1c34df5496217803e7f38a4cd0d51909537baf5d3e40067789c42ecb9e4cc28af41ef9741f661d44"} build_url:https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011447 build_url: 'https://queue.taskcluster.net/v1/task/d0PYkEtpRLay6gAB9fZa_w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ca62f93ee1fac236ea31c2d646a37928f3016658d05c92784e40ec1eb0143f9353ab5872eabb53b84a1989a71ca11f917516e4970a06131b40cb0f5d6f8446b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f692283fd6e1d5a750f514519ecc5d533c9b92d4c06d9ad6069de62c52fedcd546e3bad057868242bb7c2901c327df705c6dbc61f0463a82a41cf52a286de4ab", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/38977f6927a0625fcd269ffd2c29df47379b435ca2d2e8639b777e4bc51a3e95682638a64ab0dc895802cc6e796ecaeef71e121d39c7198ae7c8104c10aecf55", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6d944618a2239259ba92bcded4b4a9a4d269f45116ce30a1c34df5496217803e7f38a4cd0d51909537baf5d3e40067789c42ecb9e4cc28af41ef9741f661d44"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 10:21:33.555170) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:21:33.555552) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487268030.791901-2069532166 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:21:33.641578) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 10:21:33.641923) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 10:21:33.642277) ========= ========= Total master_lag: 0.15 =========